0001798: [BT] I can't pair with Apple TV.

ID	Project	Category	View Status	Date Submitted	Last Update

0001798	K18X001.00 SKB SWAN	SW	public	2023-08-22 12:49	2025-07-17 17:10

Reporter	(ALTech) Wooshin Kang	Assigned To	(ALTech) Wooshin Kang	Due Date	2023-08-25 13:46
Priority	normal	Severity	s4-minor	Reproducibility	always
Status	closed	Resolution	fixed

Summary	0001798: [BT] I can't pair with Apple TV.
Description	Swan can not pair with Apple TV 4K. Please check it.
Tags	No tags attached.
Attach Tags	(Separate by ",")

User List	(ALTech) Sangmin Choi , (ALTech) SY Yoon , (SW) Jacky Chiang , ~~(SW) Jason Ling~~ , (SW) Sam Yang Username

~~(SW) Jason Ling~~ 2023-08-22 13:58 developer ~0013940	Hi Wooshin, Can you provide the log, we don't have Apple TV 4K device, Thanks, Jason

(ALTech) Sangmin Choi 2023-08-23 08:43 developer ~0013947	Hello, Jason, Please check the attached btsnoop log. I got this log using following commands. 1. setprop persist.bluetooth.btsnooplogmode full 2. reboot the STB 3. adb pull /data/misc/bluedroid/btsnoop_hci.cfa Thank you. Sangmin Choi. btsnoop_hci_connect_apple_tv.cfa (17,210 bytes)

~~(SW) Jason Ling~~ 2023-08-23 09:57 developer ~0013950	Hi Sangmin, Do you have logcat file ? Thanks, Jason

(ALTech) Sangmin Choi 2023-08-23 10:20 developer ~0013951	Hello, Jason, Please check the attached logcat file. Thank you. Sangmin Choi. apple_tv_bt_connect_fail.log (158,801 bytes) 08-23 11:18:30.777 (2755) - D com.skb.tv: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:30.778 (2755) - D com.skb.tv: getNuguMicState() : true 08-23 11:18:30.985 system_server(681) 1645 D AS.AudioService: MSG_NUGU_UNMUTE_CHECK 08-23 11:18:30.986 system_server(681) 1645 D AS.AudioService: STREAM_NUGU stream Active recheck MSG_NUGU_UNMUTE_CHECK 08-23 11:18:31.008 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 59784 08-23 11:18:31.040 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:31.040 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 6, device 2 08-23 11:18:31.040 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:31.040 audioserver(362) 522 I chatty : uid=1041(audioserver) ApmOutput identical 2 lines 08-23 11:18:31.041 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:31.041 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 6, device 2 08-23 11:18:31.041 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 8, device 400 08-23 11:18:31.041 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 8, device 400 08-23 11:18:31.041 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:31.755 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=107, crc=a0 6b d0 a9 @@INFO:mcas-port-impl.c(739) 08-23 11:18:31.755 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:31.755 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=107, crc=a0 6b d0 a9 @@INFO:mcas-port-impl.c(739) 08-23 11:18:31.755 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:31.780 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:31.781 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:31.987 system_server(681) 1645 D AS.AudioService: MSG_NUGU_UNMUTE_CHECK 08-23 11:18:31.991 system_server(681) 1645 D AS.AudioService: STREAM_NUGU stream no Active !! mSetupWizard : falsemsg.arg1 : 0 08-23 11:18:31.991 system_server(681) 1645 D AS.AudioService: setNuguEndFlag() : 08-23 11:18:31.992 audioserver(362) 2134 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream: 3 index : 1000 device: 524288 : 08-23 11:18:31.992 system_server(681) 1645 D AS.AudioService: nugu_playback=off 08-23 11:18:31.994 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 796: adev=0xed74a000 kvpairs=nugu_playback=off 08-23 11:18:31.994 android.hardware.audio@2.0-service(341) 436 I audio_hw: JIMDBG adev_set_parameters:797, adev=0xed74a000 kvpairs=nugu_playback=off 08-23 11:18:31.994 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 1305: Set NUGU Playback off 08-23 11:18:32.008 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 52828 08-23 11:18:32.036 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 1324: change to Spdif format to (5). 08-23 11:18:32.044 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 1330: change to HDMI format to (2). 08-23 11:18:32.044 android.hardware.audio@2.0-service(341) 436 D audio_device_util: handleAudioRouting() line 1118: handleAudioRouting enter 08-23 11:18:32.044 android.hardware.audio@2.0-service(341) 436 D audio_device_util: setBtvAudioMute() line 354: ahal_dbg (354), m(0), r(0) 08-23 11:18:32.044 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [GLOBAL MUTE][externFunctionStateChangeCheckForGlobalMuteThread] event->name, btv_muted @@INFO:BtvMediaPlayerService.cpp(2085) 08-23 11:18:32.044 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [GLOBAL MUTE][externFunctionStateChangeCheckForGlobalMuteThread] IN_DELETE @@INFO:BtvMediaPlayerService.cpp(2089) 08-23 11:18:32.044 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: BTF\|changeGlobalAudioMute_l\|4853\|IN\| 08-23 11:18:32.044 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: @@INFO:IptvSocPlayer.cpp(4853) 08-23 11:18:32.044 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [INFO] AFTER MUTEX @@INFO:IptvSocPlayer.cpp(4855) 08-23 11:18:32.044 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] ######################################################### 08-23 11:18:32.045 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] START 08-23 11:18:32.045 com.skb.btvservice@1.0-service(377) 664 W btvSocHalService-JNI: btvSocHalService_GetAudioMute::474 : in 08-23 11:18:32.045 com.skb.btvsochalservice@1.0-service(379) 432 D halMediaPlayer: BTF\|AVP_GetAudioMute\|732\|IN\| player=e99a6000 08-23 11:18:32.045 com.skb.btvsochalservice@1.0-service(379) 432 D halMediaPlayer: BTF\|AVP_GetAudioMute\|743\| mute:1 08-23 11:18:32.045 com.skb.btvsochalservice@1.0-service(379) 432 D halMediaPlayer: BTF\|AVP_GetAudioMute\|747\|OUT\| 08-23 11:18:32.045 com.skb.btvservice@1.0-service(377) 664 D btvSocHalService-JNI: [operator()] mute : 1, ret : 0 08-23 11:18:32.045 com.skb.btvservice@1.0-service(377) 664 W btvSocHalService-JNI: btvSocHalService_GetAudioMute::482 : out 08-23 11:18:32.045 com.skb.btvservice@1.0-service(377) 664 W btvSocHalService-JNI: btvSocHalService_SetAudioMute::467 : in 08-23 11:18:32.045 com.skb.btvsochalservice@1.0-service(379) 432 D halMediaPlayer: BTF\|AVP_SetAudioMute\|693\|IN\| player=e99a6000, mute=0 08-23 11:18:32.046 com.skb.btvsochalservice@1.0-service(379) 432 D halMediaPlayer: ++AVP_SetAudioMute[0] unmute audio 08-23 11:18:32.046 com.skb.btvsochalservice@1.0-service(379) 432 E [ 4202.304] [35;1mAmp1@@[MODULE_SNDSRV: AMP_SND_SetStreamMute():3513]:[0m : SNDAPI tony restore volume even for HDMI passthrough ! restore volume target:[0] 08-23 11:18:32.047 com.skb.btvsochalservice@1.0-service(379) 432 D halMediaPlayer: BTF\|AVP_SetAudioMute\|726\|OUT\| 08-23 11:18:32.047 com.skb.btvservice@1.0-service(377) 664 W btvSocHalService-JNI: btvSocHalService_SetAudioMute::470 : out, opStatus:0 08-23 11:18:32.047 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] ++ unmute audio 08-23 11:18:32.047 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] END [0] 08-23 11:18:32.047 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] ######################################################### 08-23 11:18:32.047 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [changeGlobalAudioMute_l] called, mute : 0 @@INFO:IptvSocPlayer.cpp(4869) 08-23 11:18:32.047 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: BTF\|changeGlobalAudioMute_l\|4870\|OUT\| 08-23 11:18:32.047 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: @@INFO:IptvSocPlayer.cpp(4870) 08-23 11:18:32.047 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: BTF\|changeGlobalAudioMute_l\|4853\|IN\| 08-23 11:18:32.047 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: @@INFO:IptvSocPlayer.cpp(4853) 08-23 11:18:32.047 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [INFO] AFTER MUTEX @@INFO:IptvSocPlayer.cpp(4855) 08-23 11:18:32.047 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [changeGlobalAudioMute_l] called, mute : 0 @@INFO:IptvSocPlayer.cpp(4862) 08-23 11:18:32.047 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [WARNING] DEVICE IS CLOSED. WILL RETURN OK @@INFO:IptvSocPlayer.cpp(4863) 08-23 11:18:32.048 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [GLOBAL MUTE][externFunctionStateChangeCheckForGlobalMuteThread] read @@INFO:BtvMediaPlayerService.cpp(2073) 08-23 11:18:32.054 android.hardware.audio@2.0-service(341) 436 I audio_device_util: Delay 10000 us to notify aux status 08-23 11:18:32.055 android.hardware.audio@2.0-service(341) 436 D audio_device_util: handleAudioRouting() line 1396: Case for No thing connected 08-23 11:18:32.057 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 796: adev=0xed74a000 kvpairs=btv_audio_device=speaker 08-23 11:18:32.057 android.hardware.audio@2.0-service(341) 436 I audio_hw: JIMDBG adev_set_parameters:797, adev=0xed74a000 kvpairs=btv_audio_device=speaker 08-23 11:18:32.059 com.android.systemui(1731) 1925 D vol.VolumeDialogControl: onVolumeChangedW stream =3, showUI=true, fromKey=true, showNuguNoti=true 08-23 11:18:32.059 audioserver(362) 2134 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:32.060 system_server(681) 1935 D AS.AudioService: getLastAudibleStreamVolume = 0, streamType=3, device=524288 08-23 11:18:32.060 audioserver(362) 2134 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:32.063 com.android.systemui(1731) 1925 D vol.VolumeDialogControl: onVolumeChangedW changed :true 08-23 11:18:32.065 com.android.systemui(1731) 1925 I vol.Events: writeEvent key STREAM_MUSIC 0 08-23 11:18:32.066 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: updateVolumeRowH() row.stream=3, row.showUI=true, row.removeMute=false 08-23 11:18:32.066 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: updateVolumeRowH() ss.muted=false, vlevel=0 08-23 11:18:32.066 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: showVolumeZeroLevel visible: true 08-23 11:18:32.067 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: BTDeviceConnected() bConnected:false 08-23 11:18:32.068 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: isFamiltyEarphoneMode() 08-23 11:18:32.069 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: showH r=volume_changed, volZeroIconShowing:true, muteIconShowing:false 08-23 11:18:32.069 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: showH r=volume_changed, volZeroIconShowing_Nugu:false, muteIconShowing_Nugu:false 08-23 11:18:32.069 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: showH volumeUIUpdatepass : false 08-23 11:18:32.069 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: showH mActiveStream : 3 08-23 11:18:32.069 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: computeTimeoutH 08-23 11:18:32.075 com.android.systemui(1731) - I vol.Events: writeEvent show_dialog volume_changed keyguard=false 08-23 11:18:32.077 system_server(681) 1935 D AS.AudioService: Volume controller visible: true 08-23 11:18:32.218 com.skt.aidev.nugustb(18133) 18236 I libTycheWakeup: Wakeup health check (2): feat.cnt=43000, pwr=23716.000000, score=-0.992010 08-23 11:18:32.258 system_server(681) 1645 D AS.AudioService: MSG_NUGU_START_NOTI : 08-23 11:18:32.258 system_server(681) 1645 D AS.AudioService: setBtvUnmute start 08-23 11:18:32.258 audioserver(362) 2134 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:32.258 system_server(681) 1645 D AS.AudioService: getLastAudibleStreamVolume = 0, streamType=3, device=524288 08-23 11:18:32.258 system_server(681) 1645 D AS.AudioService: setBtvUnmute STREAM_MUSIC sucess btv_oldindex =0mAvrcpAbsVolSupported= false 08-23 11:18:32.258 system_server(681) 1645 D AS.AudioService: setBtvUnmute STREAM_MUSIC btv_oldindex : 0 08-23 11:18:32.258 audioserver(362) 2134 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream: 3 index : 0 device: 524288 : 08-23 11:18:32.258 audioserver(362) 2134 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume mExtMute: 0, mExtCC: 0 08-23 11:18:32.258 audioserver(362) 2134 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream : 3 volume_index : 0 gain : -758.000000 08-23 11:18:32.258 audioserver(362) 2134 D BtvMediaVolumeControlLib: btvMediaPlayerVolumeControl signal, newVolume:0, -758.000000 08-23 11:18:32.258 audioserver(362) 2134 E APM_AudioPolicyManager: setStreamVolumeIndex: Btv not play !!! set_btv_media_volume: 08-23 11:18:32.259 com.skb.btvservice@1.0-service(377) - W btvSocHalService-JNI: btvSocHalService_SetMediaAudioVolume::526 : in 08-23 11:18:32.259 com.skb.btvsochalservice@1.0-service(379) - D BTV_HAL_MGR.606: [useSpeakerVolumePath] useSpeakerVolumePath : true 08-23 11:18:32.259 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1058\|IN\| player=e99a6000, volume=0 gain=-758.000000 08-23 11:18:32.259 com.skb.btvsochalservice@1.0-service(379) - E halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1067\|IN\| load volume, checkVolume : 0, checkGain : -758.000000 08-23 11:18:32.259 com.skb.btvservice@1.0-service(377) 2046 I TVService-23.01.26: [TVService::setMicVolume]: volume(0) 08-23 11:18:32.259 com.skb.btvservice@1.0-service(377) 2046 I FrontPanelManager: [setMicVolume] called volume(0) 08-23 11:18:32.259 com.skb.btvservice@1.0-service(377) 2046 W btvSocHalService-JNI: btvSocHalService_SetMicVolume::1037 : in 08-23 11:18:32.259 com.skb.btvsochalservice@1.0-service(379) 432 D DEVICE : BTF\|DEVICE_SetMicVolume\|339\|IN\| device:ea410278, volume_level:0 08-23 11:18:32.259 com.skb.btvsochalservice@1.0-service(379) 432 D DEVICE : ++DEVICE_SetMicVolume volume_level(0) 08-23 11:18:32.259 com.skb.btvsochalservice@1.0-service(379) 432 I hal_mic : HAL_MicSetVolume v103(volume=0) 08-23 11:18:32.261 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1117\|OUT\| 08-23 11:18:32.261 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1058\|IN\| player=0, volume=0 gain=-758.000000 08-23 11:18:32.262 com.skb.btvsochalservice@1.0-service(379) - E halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1067\|IN\| load volume, checkVolume : 0, checkGain : -758.000000 08-23 11:18:32.262 com.skb.btvsochalservice@1.0-service(379) - E halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1085\|IN\| player=0, return !!! 08-23 11:18:32.262 com.skb.btvservice@1.0-service(377) - W btvSocHalService-JNI: btvSocHalService_SetMediaAudioVolume::529 : out, opStatus:0 08-23 11:18:32.262 audioserver(362) 2045 D BtvMediaVolumeControlLib: status : 0 08-23 11:18:32.263 com.skb.btvsochalservice@1.0-service(379) 432 D hal_mic : set /sys/devices/platform/soc/soc:apb@e80000/f7e81400.i2c/i2c-0/0-0018/adc3101_vol_ctl=0 08-23 11:18:32.263 com.skb.btvsochalservice@1.0-service(379) 432 I hal_mic : HAL_MicSetVolume set vol immediately 08-23 11:18:32.265 com.skb.btvsochalservice@1.0-service(379) 432 D hal_mic : set /sys/devices/platform/soc/soc:apb@e80000/f7e81400.i2c/i2c-0/0-0018/adc3101_mute_ctl=1 08-23 11:18:32.265 com.skb.btvsochalservice@1.0-service(379) 432 I hal_mic : HAL_MicSetVolume, 880,for Karaoke MIC, lv 0, priv_gs_mic_volume 0 08-23 11:18:32.265 com.skb.btvsochalservice@1.0-service(379) 432 D DEVICE : BTF\|DEVICE_SetMicVolume\|366\|OUT\| 08-23 11:18:32.266 com.skb.btvservice@1.0-service(377) 2046 W btvSocHalService-JNI: btvSocHalService_SetMicVolume::1040 : out, opStatus:0 08-23 11:18:32.266 audioserver(362) 2134 D BtvMediaVolumeControlLib: status : 1 08-23 11:18:32.267 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 796: adev=0xed74a000 kvpairs=btv_volume_index=0 08-23 11:18:32.267 android.hardware.audio@2.0-service(341) 436 I audio_hw: JIMDBG adev_set_parameters:797, adev=0xed74a000 kvpairs=btv_volume_index=0 08-23 11:18:32.268 audioserver(362) 2134 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:32.268 system_server(681) 1645 D AS.AudioService: setBtvUnmute end mIsBtvMute = false 08-23 11:18:32.277 android.hardware.audio@2.0-service(341) 18234 D MPP-PREPROCESS: [Tyche_MC_PreProc_Run] [CHANGE] SPK_MODE=1, SPK_VOL=0 08-23 11:18:32.280 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:18:32.779 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:32.780 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:33.012 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 47188 08-23 11:18:33.780 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=81, crc=11 a9 ff 7e @@INFO:mcas-port-impl.c(739) 08-23 11:18:33.780 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:33.780 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=81, crc=11 a9 ff 7e @@INFO:mcas-port-impl.c(739) 08-23 11:18:33.781 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:33.781 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:33.782 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:34.004 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 52452 08-23 11:18:34.629 skb_logsystem(2232) 2324 I QSM_RTP_server: RTP_analyzer : No data within 120 seconds. (0) 08-23 11:18:34.782 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:34.783 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:35.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 56400 08-23 11:18:35.071 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: dismissH() mActiveStream :3 08-23 11:18:35.071 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: dismissH r=timeout ss.muted=false 08-23 11:18:35.072 com.android.systemui(1731) - I vol.Events: writeEvent dismiss_dialog timeout 08-23 11:18:35.447 com.skb.tv(2755) - I chromium: [INFO:CONSOLE(2)] "###################################################", source: https://agw.sk-iptv.com:8443/ui5web/v540/nuxt/js/app.js?1187d83b57b70e63f4a5 (2) 08-23 11:18:35.448 com.skb.tv(2755) - I chromium: [INFO:CONSOLE(2)] "[Memory] usedJSHeapSize : 18740280", source: https://agw.sk-iptv.com:8443/ui5web/v540/nuxt/js/app.js?1187d83b57b70e63f4a5 (2) 08-23 11:18:35.448 com.skb.tv(2755) - I chromium: [INFO:CONSOLE(2)] "[Memory] totalJSHeapSize : 27682052", source: https://agw.sk-iptv.com:8443/ui5web/v540/nuxt/js/app.js?1187d83b57b70e63f4a5 (2) 08-23 11:18:35.448 com.skb.tv(2755) - I chromium: [INFO:CONSOLE(2)] "[Memory] jsHeapSizeLimit : 553648128", source: https://agw.sk-iptv.com:8443/ui5web/v540/nuxt/js/app.js?1187d83b57b70e63f4a5 (2) 08-23 11:18:35.448 com.skb.tv(2755) - I chromium: [INFO:CONSOLE(2)] "###################################################", source: https://agw.sk-iptv.com:8443/ui5web/v540/nuxt/js/app.js?1187d83b57b70e63f4a5 (2) 08-23 11:18:35.782 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:35.783 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:36.018 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 60912 08-23 11:18:36.783 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:36.784 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:37.011 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 59408 08-23 11:18:37.281 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:18:37.524 skb_logsystem(2232) 2292 I QSMServer_LogSystem: QSM s_qsm_tx_cond_timed_wait : exit tx thread timed wait 08-23 11:18:37.525 skb_logsystem(2232) 2292 I QSMServer_LogSystem: QSM s_qsm_tx_cond_timed_wait : enter tx thread timed wait 08-23 11:18:37.787 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:37.787 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:38.015 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 61664 08-23 11:18:38.788 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:38.788 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:39.014 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 45496 08-23 11:18:39.405 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:18:39.407 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:18:39.411 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:18:39.412 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:18:39.424 com.skb.tv(2755) - I TvNotificationListenerService: onNotificationPosted() 08-23 11:18:39.443 com.skb.tv(2755) 13254 I TvNotificationManager: getNotificationCount() count : 1 08-23 11:18:39.788 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:39.789 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:40.005 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 44180 08-23 11:18:40.763 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=68, crc=1f ca 64 9b @@INFO:mcas-port-impl.c(739) 08-23 11:18:40.763 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:40.763 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=68, crc=1f ca 64 9b @@INFO:mcas-port-impl.c(739) 08-23 11:18:40.765 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:40.789 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:40.790 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:41.009 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 47752 08-23 11:18:41.258 com.google.android.katniss:interactor(11959) 17721 D BindingManager: with 08-23 11:18:41.265 com.google.android.apps.mediashell(4508) - D cr_GrpcIpcUidPolicy: [AndroidIpcUidPolicy.java:147] Valid access from com.google.android.katniss 08-23 11:18:41.265 com.google.android.apps.mediashell(4508) - E cr_GrpcIpcSocketService: Native code not initialized yet. 08-23 11:18:41.266 com.google.android.katniss:interactor(11959) - E GrpcIpcSocketClientImpl: invalid message 08-23 11:18:41.268 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.266818 17721 grpc_ipc_socket_client.cc:77] Error for usonia-ipc-socket 08-23 11:18:41.268 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.268111 17721 android_grpc_builder.cc:149] Could not get a socket for binder:com.google.android.apps.mediashell:usonia-ipc-socket 08-23 11:18:41.268 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.268327 17721 grpc_manager_impl.cc:640] GRPC[uri=]: Grpc cannot connect to host {UNDEFINED} 08-23 11:18:41.268 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.268419 17721 grpc_manager_impl.cc:788] GRPC[uri=]: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.268 com.google.android.katniss:interactor(11959) 17721 W native : W0000 00:00:1692757121.268484 17721 grpc_auth_manager_with_grpc_ipc.cc:65] IPC for Auth.AuthReadyStreaming closed: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.268 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.268558 17721 grpc_manager_impl.cc:788] GRPC[uri=]: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.268 com.google.android.katniss:interactor(11959) 17721 W native : W0000 00:00:1692757121.268615 17721 distributed_service_discovery_with_grpc_ipc.cc:150] IPC for DistributedServiceDiscovery.AddService closed: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.268 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.268689 17721 grpc_manager_impl.cc:788] GRPC[uri=]: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.268 com.google.android.katniss:interactor(11959) 17721 W native : W0000 00:00:1692757121.268758 17721 distributed_service_discovery_with_grpc_ipc.cc:64] IPC for DistributedServiceDiscovery.UpdateObservers closed: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.268 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.268831 17721 grpc_manager_impl.cc:788] GRPC[uri=]: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.268 com.google.android.katniss:interactor(11959) 17721 W native : W0000 00:00:1692757121.268891 17721 distributed_service_discovery_with_grpc_ipc.cc:251] IPC for DistributedServiceDiscovery.GetIamMode closed: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.268 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.268964 17721 grpc_manager_impl.cc:788] GRPC[uri=]: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 W native : W0000 00:00:1692757121.269023 17721 metrics_recorder_with_grpc_ipc.cc:164] IPC for MetricsRecorder.RecordMetrics closed: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.269171 17721 grpc_manager_impl.cc:788] GRPC[uri=]: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 W native : W0000 00:00:1692757121.269235 17721 synchronous_sound_player_with_grpc_ipc.cc:173] IPC for SynchronousSoundPlayer.UpdateSound closed: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.269309 17721 grpc_manager_impl.cc:788] GRPC[uri=]: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.269366 17721 ipc_common.h:485] Error in fetching local device info: [error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.269440 17721 grpc_manager_impl.cc:788] GRPC[uri=]: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 W native : W0000 00:00:1692757121.269498 17721 directory_service_with_grpc_ipc.cc:123] IPC for DirectoryService.ObserveCommonTime closed: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.269572 17721 grpc_manager_impl.cc:788] GRPC[uri=]: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.269629 17721 directory_service_with_grpc_ipc.cc:277] IPC for DirectoryService.UpdateStateObservers closed: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.269704 17721 grpc_manager_impl.cc:788] GRPC[uri=]: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.269761 17721 directory_service_with_grpc_ipc.cc:378] IPC for DirectoryService.UpdateMessageObservers closed: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 E native : E0000 00:00:1692757121.269831 17721 grpc_manager_impl.cc:788] GRPC[uri=]: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.269 com.google.android.katniss:interactor(11959) 17721 W native : W0000 00:00:1692757121.269889 17721 state_manager_with_grpc_ipc.cc:74] IPC for StateManager.ReceiveStateUpdates closed: status=[error_code=RESOURCE_EXHAUSTED, error_message=Cannot create a channel. Call to binder:com.google.android.apps.mediashell:usonia-ipc-socket ignored.] 08-23 11:18:41.757 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=68, crc=cb 79 8e fc @@INFO:mcas-port-impl.c(739) 08-23 11:18:41.757 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:41.757 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=68, crc=cb 79 8e fc @@INFO:mcas-port-impl.c(739) 08-23 11:18:41.757 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:41.789 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:41.790 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:42.015 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 47376 08-23 11:18:42.123 system_server(681) 822 D WindowManager: interceptKeyTq keycode=333 interactive=true keyguardActive=false policyFlags=22000000 08-23 11:18:42.123 system_server(681) 822 D WindowManager: interceptKeyTq keycode=333 interactive=true keyguardActive=false policyFlags=22000000 08-23 11:18:42.123 system_server(681) 822 D WindowManager: keycode : KEYCODE_VAS_FUNC_21 08-23 11:18:42.123 system_server(681) 822 W WindowManager: sendBroadcastAsUser action : com.vas.intent.action.PUSH_BT_BUTTON, matches.size : 1 08-23 11:18:42.123 system_server(681) 822 W WindowManager: sendBroadcastAsUser action : com.vas.intent.action.PUSH_BT_BUTTON, ComponentName : ComponentInfo{com.vasott.a2dpdeviceservice/com.vasott.a2dpdeviceservice.BcastReceiver} 08-23 11:18:42.133 com.vasott.a2dpdeviceservice(5338) - W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.startService:1570 android.content.ContextWrapper.startService:669 android.content.ContextWrapper.startService:669 com.vasott.a2dpdeviceservice.BcastReceiver.onReceive:22 android.app.ActivityThread.handleReceiver:3788 08-23 11:18:42.136 com.vasott.a2dpdeviceservice(5338) - I a2dpd : 08-23 11:18:42.136 com.vasott.a2dpdeviceservice(5338) - I a2dpd : ######### EventHandler, event = EVENT_BTN, state = A:STATE_IDLE 08-23 11:18:42.147 com.vasott.a2dpdeviceservice(5338) - D a2dpd : Paired Device: connected=true, class=1292, has RENDER = false, has CAPTURE = false, name = BRM_BA02_458 08-23 11:18:42.158 com.vasott.a2dpdeviceservice(5338) - I a2dpd : set bt-sink-mode... already in sink mode 08-23 11:18:42.158 com.vasott.a2dpdeviceservice(5338) - W a2dpd : ### vendor.skb.dev.bt.sinkmode = 1 08-23 11:18:42.159 com.skt.aidev.nugustb(18133) 18236 I libTycheWakeup: Wakeup health check (2): feat.cnt=44000, pwr=964324.000000, score=-0.989080 08-23 11:18:42.160 com.vasott.a2dpdeviceservice(5338) - W a2dpd : ### setScanMode = 23 08-23 11:18:42.162 com.android.bluetooth(1709) 1814 D AdapterProperties: Discoverable Timeout:120 08-23 11:18:42.162 com.vasott.a2dpdeviceservice(5338) - W a2dpd : ### simulate SKB_FUNC_31 KEY 08-23 11:18:42.163 com.vasott.a2dpdeviceservice(5338) - W a2dpd : ### nugu-response command = on 08-23 11:18:42.163 com.vasott.a2dpdeviceservice(5338) - W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1045 android.content.ContextWrapper.sendBroadcast:448 com.vasott.a2dpdeviceservice.A2dpDeviceService.responseNUGUSwitchMode:929 com.vasott.a2dpdeviceservice.A2dpDeviceService$1.handleMessage:162 android.os.Handler.dispatchMessage:107 08-23 11:18:42.164 com.skb.tv(2755) - D BluetoothConnectionManager: [ mBluetoothReceiver ] action= .SCAN_MODE_CHANGED, , deviceBoundState= -1, conncetionState= -1 08-23 11:18:42.164 com.skt.aidev.device.connect(3592) - D TVSIntentReceiver: onReceive : android.bluetooth.adapter.action.SCAN_MODE_CHANGED 08-23 11:18:42.164 com.skt.aidev.device.connect(3592) - D TVSIntentReceiver: scanModeStateChanged : 23 08-23 11:18:42.164 com.skt.aidev.device.connect(3592) - D LedManager: handleLedStatesBT_PAIRING_ON 08-23 11:18:42.164 com.skt.aidev.device.connect(3592) - D LedManager: newPattern = 1081 08-23 11:18:42.164 com.skt.aidev.device.connect(3592) - W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1045 android.content.ContextWrapper.sendBroadcast:448 com.skt.aidev.device.connect.business.LedManager.handleLedStates:279 com.skt.aidev.device.connect.receiver.TVSIntentReceiver.onReceive:235 android.app.LoadedApk$ReceiverDispatcher$Args.lambda$getRunnable$0$LoadedApk$ReceiverDispatcher$Args:1550 08-23 11:18:42.165 system_server(681) 5159 D WindowManager: interceptKeyTq keycode=435 interactive=true keyguardActive=false policyFlags=2b000000 08-23 11:18:42.165 system_server(681) 5159 D WindowManager: interceptKeyTq keycode= 435 isValidGlobalKey 08-23 11:18:42.166 system_server(681) 821 D WindowManager: interceptKeyTi keyCode=435 down=true repeatCount=0 keyguardOn=false canceled=false policyFlags=1795162112 mDeviceId=-1 mSource=257 mScanCode=0 mCharacters=null 08-23 11:18:42.167 com.android.bluetooth(1709) 1814 D AdapterProperties: Scan Mode:23 08-23 11:18:42.169 system_server(681) 821 D WindowManager: interceptKeyTi strDate 2023-08-23 11:18 08-23 11:18:42.170 com.vasott.a2dpdeviceservice(5338) - I a2dpd : ######### EventHandler end, now state = B:STATE_DISCOVERIBLE 08-23 11:18:42.170 com.vasott.a2dpdeviceservice(5338) - D a2dpd : ==> onReceive ACTION_SCAN_MODE_CHANGED mode=23 08-23 11:18:42.170 system_server(681) 821 D WindowManager: interceptSkbFuctionKeyBeforeDispatching keycode = 435 08-23 11:18:42.170 system_server(681) 821 D WindowManager: keycode : 435 - processed. by skb function key 08-23 11:18:42.171 system_server(681) 821 W GlobalKeyManager: handleGlobalKey keyCode : 435 componentComponentInfo{com.skb.btv.framework/com.skb.btv.framework.BtvStartReceiver} 08-23 11:18:42.173 com.skb.btv.framework(3547) - I BtvService IBtvService: APK_7.1.07 BTF\|onReceive\|67\|IN\| DeviceCtrlServiceReceiver::onReceive action = com.skbb.intent.action.NUGU_BT_CTRL_RESPONSE, BUILD_DATE:2023.07.20 08-23 11:18:42.174 com.skb.btv.framework(3547) - I BtvService IBtvService: APK_7.1.07 BTF\|onReceive\|147\| DeviceCtrlServiceReceiver::onReceive : NUGU_BT_CTRL_RESPONSE command = on 08-23 11:18:42.174 com.skb.btv.framework(3547) - W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1045 android.content.ContextWrapper.sendBroadcast:448 com.skb.btv.framework.DeviceCtrlServiceReceiver.onReceive:154 android.app.LoadedApk$ReceiverDispatcher$Args.lambda$getRunnable$0$LoadedApk$ReceiverDispatcher$Args:1550 android.app.-$$Lambda$LoadedApk$ReceiverDispatcher$Args$_BumDX2UKsnxLVrE6UJsJZkotuA.run:2 08-23 11:18:42.176 system_server(681) 1935 D WindowManager: interceptKeyTq keycode=435 interactive=true keyguardActive=false policyFlags=2b000000 08-23 11:18:42.176 system_server(681) 1935 D WindowManager: interceptKeyTq keycode= 435 isValidGlobalKey 08-23 11:18:42.176 system_server(681) 821 D WindowManager: interceptKeyTi keyCode=435 down=false repeatCount=0 keyguardOn=false canceled=false policyFlags=1795162112 mDeviceId=-1 mSource=257 mScanCode=0 mCharacters=null 08-23 11:18:42.176 system_server(681) 821 D WindowManager: interceptSkbFuctionKeyBeforeDispatching keycode = 435 08-23 11:18:42.177 com.skb.btv.framework(3547) - I BtvService IBtvService: APK_7.1.07 BTF\|onReceive\|399\|OUT\| DeviceCtrlServiceReceiver:: END 08-23 11:18:42.177 com.skb.btv.framework(3547) - D BtvService IBtvService: BtvStartReceiver::create 08-23 11:18:42.177 com.skb.btv.framework(3547) - D BtvService IBtvService: IN\| BtvStartReceiver::onReceive getAction = android.intent.action.GLOBAL_BUTTON 08-23 11:18:42.177 com.skb.btv.framework(3547) - I BtvService IBtvService: APK_7.1.07 BTF\|onReceive\|171\| BtvStartReceiver::onReceive : ACTION_WINDOWMANAGE_BTV_GLOBAL_BUTTON : date:2023.07.20 08-23 11:18:42.177 com.skb.btv.framework(3547) - E BtvService IBtvService: BtvStartReceiver::android.intent.action.GLOBAL_BUTTON keyCode = 435 action = 0, KeyEvent.ACTION_UP1, KeyEvent.ACTION_DOWN0 08-23 11:18:42.177 com.skb.btv.framework(3547) - I BtvService IBtvService: APK_7.1.07 BTF\|onReceive\|279\|OUT\| BtvStartReceiver::onReceive 08-23 11:18:42.179 system_server(681) 821 D WindowManager: keycode : 435 - processed. by skb function key 08-23 11:18:42.179 system_server(681) 821 W GlobalKeyManager: handleGlobalKey keyCode : 435 componentComponentInfo{com.skb.btv.framework/com.skb.btv.framework.BtvStartReceiver} 08-23 11:18:42.180 com.skb.btv.framework(3547) - I BtvService PeripheralService: APK_7.1.07 BTF\|onReceive\|54\| LEDControl:onReceive : com.skb.intent.KEY_LED_BLINK 08-23 11:18:42.181 com.skb.btv.framework(3547) - W BtvService BtvJni`: frontPanelSetLedState index: 3, state: 2, StateQ.size(): 0, BQ.remainingCapa(): 10 08-23 11:18:42.181 com.skb.btv.framework(3547) 3690 W BtvService BtvJni`: frontPanelSetLedState take after 08-23 11:18:42.181 com.skb.btv.framework(3547) 3690 D BTFSerJni[6][22.06.08]: tvcoreJni_frontPanelSetLedState start 08-23 11:18:42.181 com.skb.btvservice@1.0-service(377) - I TVService-23.01.26: [TVService::frontPanelSetLedState] index(3) state(2). 08-23 11:18:42.181 com.skb.btvservice@1.0-service(377) - I FrontPanelManager: [frontPanelSetLedState] called index(3) state(2) 08-23 11:18:42.181 com.skb.btvservice@1.0-service(377) - W btvSocHalService-JNI: btvSocHalService_SetLedState::991 : in 08-23 11:18:42.182 com.skb.btvsochalservice@1.0-service(379) - D DEVICE : BTF\|DEVICE_SetLedState\|130\|IN\| device:ea410278, led_id:3, state:2 08-23 11:18:42.182 com.skb.btvsochalservice@1.0-service(379) - D DEVICE : ++DEVICE_SetLedState led_id(3), state(2) 08-23 11:18:42.182 com.skb.btvsochalservice@1.0-service(379) - I HAL_frontpanel: HAL_FrontPanelSetLedState, 74, in, LED idx 3, state 2 08-23 11:18:42.182 ledd(754) - D HAL_frontpanel: ledd: parse_fifo_cmd:117, op=state, ret_op=1, index=3, state=2, volume=0, color=0, degree=0, cmd="state 3 2" 08-23 11:18:42.182 ledd(754) - I FXC_STUB_LP50XX: wait_st_thread_clean, 3421, in 08-23 11:18:42.182 ledd(754) - I FXC_STUB_LP50XX: lp50xx_set_status_led, 3475, cmd: index 3, state 2 08-23 11:18:42.182 com.skb.btvsochalservice@1.0-service(379) - D DEVICE : BTF\|DEVICE_SetLedState\|155\|OUT\| 08-23 11:18:42.182 com.skb.btvservice@1.0-service(377) - W btvSocHalService-JNI: btvSocHalService_SetLedState::994 : out, opStatus:0 08-23 11:18:42.183 com.skb.btv.framework(3547) 3690 W BtvService BtvJni`: frontPanelSetLedState after jni, index: 3, status: 2, result: true 08-23 11:18:42.183 com.skb.btv.framework(3547) 3690 W BtvService BtvJni`: frontPanelSetLedState take before =============================================== 08-23 11:18:42.185 com.skb.btv.framework(3547) - D BtvService IBtvService: BtvStartReceiver::create 08-23 11:18:42.186 com.skb.btv.framework(3547) - D BtvService IBtvService: IN\| BtvStartReceiver::onReceive getAction = android.intent.action.GLOBAL_BUTTON 08-23 11:18:42.186 com.skb.btv.framework(3547) - I BtvService IBtvService: APK_7.1.07 BTF\|onReceive\|171\| BtvStartReceiver::onReceive : ACTION_WINDOWMANAGE_BTV_GLOBAL_BUTTON : date:2023.07.20 08-23 11:18:42.186 com.skb.btv.framework(3547) - E BtvService IBtvService: BtvStartReceiver::android.intent.action.GLOBAL_BUTTON keyCode = 435 action = 1, KeyEvent.ACTION_UP1, KeyEvent.ACTION_DOWN0 08-23 11:18:42.186 com.skb.btv.framework(3547) - E BtvService IBtvService: BtvStartReceiver::NUGU_CALL_STATE = 08-23 11:18:42.186 com.skb.btv.framework(3547) - W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:1045 android.content.ContextWrapper.sendBroadcast:448 android.content.ContextWrapper.sendBroadcast:448 com.skb.btv.framework.BtvStartReceiver.onReceive:213 android.app.ActivityThread.handleReceiver:3788 08-23 11:18:42.188 com.skb.btv.framework(3547) - I BtvService IBtvService: APK_7.1.07 BTF\|onReceive\|279\|OUT\| BtvStartReceiver::onReceive 08-23 11:18:42.189 system_server(681) - D AS.AudioService: SKBB_AUDIO_EXTERNAL_EVENT_CONTROL_ACTION nugu_state = BT_PAIRING_ON 08-23 11:18:42.189 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 796: adev=0xed74a000 kvpairs=nugu_amp=off 08-23 11:18:42.189 android.hardware.audio@2.0-service(341) 436 I audio_hw: JIMDBG adev_set_parameters:797, adev=0xed74a000 kvpairs=nugu_amp=off 08-23 11:18:42.189 android.hardware.audio@2.0-service(341) 436 I audio_hw: [charmk]===============> NUGU AMP OFF 08-23 11:18:42.189 com.skb.btv.framework(3547) - I BtvService IBtvService: APK_7.1.07 BTF\|onReceive\|67\|IN\| DeviceCtrlServiceReceiver::onReceive action = com.skbb.intent.action.NUGU_LED_PATTERN, BUILD_DATE:2023.07.20 08-23 11:18:42.190 com.skb.btv.framework(3547) - I BtvService IBtvService: APK_7.1.07 BTF\|onReceive\|191\| DeviceCtrlServiceReceiver::onReceive : state = BT_PAIRING_ON 08-23 11:18:42.190 com.skb.btv.framework(3547) - I BtvService IBtvService: APK_7.1.07 BTF\|onReceive\|399\|OUT\| DeviceCtrlServiceReceiver:: END 08-23 11:18:42.190 system_server(681) - D AS.AudioService: setNuguStartFlag() : 08-23 11:18:42.190 system_server(681) - D AS.AudioService: setBtvMute start 08-23 11:18:42.190 system_server(681) - D AS.AudioService: setBtvMute STREAM_MUSIC sucess 08-23 11:18:42.193 audioserver(362) 3983 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream: 3 index : -3000 device: 524288 : 08-23 11:18:42.193 audioserver(362) 3983 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume mExtMute: 0, mExtCC: 0 08-23 11:18:42.193 audioserver(362) 3983 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream : 3 volume_index : 0 gain : -758.000000 08-23 11:18:42.193 audioserver(362) 3983 D BtvMediaVolumeControlLib: btvMediaPlayerVolumeControl signal, newVolume:0, -758.000000 08-23 11:18:42.193 audioserver(362) 3983 E APM_AudioPolicyManager: setStreamVolumeIndex: Btv not play !!! set_btv_media_volume: 08-23 11:18:42.193 com.skb.btvservice@1.0-service(377) 2046 W btvSocHalService-JNI: btvSocHalService_SetMediaAudioVolume::526 : in 08-23 11:18:42.193 com.skb.btvsochalservice@1.0-service(379) - D BTV_HAL_MGR.606: [useSpeakerVolumePath] useSpeakerVolumePath : true 08-23 11:18:42.193 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1058\|IN\| player=e99a6000, volume=0 gain=-758.000000 08-23 11:18:42.193 com.skb.btvsochalservice@1.0-service(379) - E halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1067\|IN\| load volume, checkVolume : 0, checkGain : -758.000000 08-23 11:18:42.194 com.skb.btvservice@1.0-service(377) 2047 I TVService-23.01.26: [TVService::setMicVolume]: volume(0) 08-23 11:18:42.194 com.skb.btvservice@1.0-service(377) 2047 I FrontPanelManager: [setMicVolume] called volume(0) 08-23 11:18:42.194 com.skb.btvservice@1.0-service(377) 2047 W btvSocHalService-JNI: btvSocHalService_SetMicVolume::1037 : in 08-23 11:18:42.194 com.skb.btvsochalservice@1.0-service(379) 432 D DEVICE : BTF\|DEVICE_SetMicVolume\|339\|IN\| device:ea410278, volume_level:0 08-23 11:18:42.194 com.skb.btvsochalservice@1.0-service(379) 432 D DEVICE : ++DEVICE_SetMicVolume volume_level(0) 08-23 11:18:42.194 com.skb.btvsochalservice@1.0-service(379) 432 I hal_mic : HAL_MicSetVolume v103(volume=0) 08-23 11:18:42.194 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1117\|OUT\| 08-23 11:18:42.194 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1058\|IN\| player=0, volume=0 gain=-758.000000 08-23 11:18:42.194 com.skb.btvsochalservice@1.0-service(379) - E halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1067\|IN\| load volume, checkVolume : 0, checkGain : -758.000000 08-23 11:18:42.194 com.skb.btvsochalservice@1.0-service(379) - E halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1085\|IN\| player=0, return !!! 08-23 11:18:42.195 com.skb.btvservice@1.0-service(377) 2046 W btvSocHalService-JNI: btvSocHalService_SetMediaAudioVolume::529 : out, opStatus:0 08-23 11:18:42.195 audioserver(362) 2045 D BtvMediaVolumeControlLib: status : 0 08-23 11:18:42.196 com.skt.aidev.device.connect(3592) - D TVSIntentReceiver: SKIP LED CONTROL BECAUSE NUGU CALL IS GOING ON 08-23 11:18:42.196 com.skt.aidev.device.connect(3592) - D TVSIntentReceiver: onReceive : com.tvstorm.tv.NUGU_BT_CTRL_RESPONSE 08-23 11:18:42.196 com.skt.aidev.device.connect(3592) - D TVSIntentReceiver: BT Ctrl Response : on 08-23 11:18:42.196 com.skb.btvsochalservice@1.0-service(379) 432 D hal_mic : set /sys/devices/platform/soc/soc:apb@e80000/f7e81400.i2c/i2c-0/0-0018/adc3101_vol_ctl=0 08-23 11:18:42.196 com.skb.btvsochalservice@1.0-service(379) 432 I hal_mic : HAL_MicSetVolume set vol immediately 08-23 11:18:42.196 com.skt.aidev.device.connect(3592) - D TVSIntentReceiver: onReceive : com.tvstorm.tv.NUGU_BT_CTRL_RESPONSE 08-23 11:18:42.196 com.skt.aidev.nugustb(18133) 18178 I NuguDeviceApp: onBTState, state = on 08-23 11:18:42.197 com.skb.btvsochalservice@1.0-service(379) 432 D hal_mic : set /sys/devices/platform/soc/soc:apb@e80000/f7e81400.i2c/i2c-0/0-0018/adc3101_mute_ctl=1 08-23 11:18:42.197 com.skb.btvsochalservice@1.0-service(379) 432 I hal_mic : HAL_MicSetVolume, 880,for Karaoke MIC, lv 0, priv_gs_mic_volume 0 08-23 11:18:42.197 com.skb.btvsochalservice@1.0-service(379) 432 D DEVICE : BTF\|DEVICE_SetMicVolume\|366\|OUT\| 08-23 11:18:42.200 com.skb.btvservice@1.0-service(377) 2047 W btvSocHalService-JNI: btvSocHalService_SetMicVolume::1040 : out, opStatus:0 08-23 11:18:42.200 audioserver(362) 3983 D BtvMediaVolumeControlLib: status : 1 08-23 11:18:42.200 audioserver(362) 3983 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.205 system_server(681) - D AS.AudioService: setBtvMute end mIsBtvMute = true 08-23 11:18:42.205 audioserver(362) 2134 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream: 3 index : -1000 device: 524288 : 08-23 11:18:42.205 system_server(681) - D AS.AudioService: SKBB_AUDIO_EXTERNAL_EVENT_CONTROL_ACTION volume bar not update 08-23 11:18:42.206 com.skt.aidev.device.connect(3592) - D TVSIntentReceiver: BT Ctrl Response : on 08-23 11:18:42.206 com.skt.aidev.nugustb(18133) 18178 I NuguDeviceApp: play embedded tts: <skml>블루투스 연결이 준비되었습니다. 연결 가능한 기기의 블루투스 설정 화면에서 누구 디바이스에 연결 해주세요</skml> 08-23 11:18:42.211 com.skt.aidev.nugustb(18133) 18178 W MediaPlayer: Use of stream types is deprecated for operations other than volume control 08-23 11:18:42.211 com.skt.aidev.nugustb(18133) 18178 W MediaPlayer: See the documentation of setAudioStreamType() for what to use instead with android.media.AudioAttributes to qualify your playback use case 08-23 11:18:42.212 mediaserver(420) 3471 D NuPlayerDriver: NuPlayerDriver(0xe5b33500) created, clientPid(18133) 08-23 11:18:42.214 mediaserver(420) 3471 I Codec2Client: Creating a Codec2 client to service "software" 08-23 11:18:42.221 mediaserver(420) 3471 I Codec2Client: Client to Codec2 service "software" created 08-23 11:18:42.223 mediaserver(420) 19291 D GenericSource: FileSource remote 08-23 11:18:42.241 mediaserver(420) 19290 I GenericSource: start 08-23 11:18:42.245 com.skt.aidev.nugustb(18133) 18178 I NuguDeviceApp: onBTState, state = on 08-23 11:18:42.246 system_server(681) 1935 I MediaFocusControl: requestAudioFocus() from uid/pid 10028/18133 clientId=android.media.AudioManager@f97d8d5m7.b@17668ea callingPack=com.skt.aidev.nugustb req=4 flags=0x0 sdk=31 08-23 11:18:42.247 audioserver(362) 3983 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.247 mediaserver(420) 19295 D CCodec : allocate(c2.android.vorbis.decoder) 08-23 11:18:42.248 media.swcodec(438) 1866 V C2Store : in init 08-23 11:18:42.248 media.swcodec(438) 1866 V C2Store : loading dll 08-23 11:18:42.250 com.skb.tv(2755) - D AudioManager: dispatching onAudioFocusChange(-2) to android.media.AudioManager@839d7d6com.skb.google.tv.global.-$$Lambda$AudioFocusManager$JmF9i4PTPURJn676IPzdIL4WBms@5e84a57 08-23 11:18:42.250 com.skb.tv(2755) - D AudioFocusManager: mAudioFocusChangeListener.onAudioFocusChange() AUDIOFOCUS_LOSS_TRANSIENT 08-23 11:18:42.252 com.skt.aidev.nugustb(18133) 19212 I NuguAlertAgent: [onFocusChanged] stop alarm by TTS 08-23 11:18:42.253 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: CISQMSHolePunching::send_keep_alive_to_report_server create_holepunching_msg 08-23 11:18:42.253 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: INFO: NET make_holepunching_msg 08-23 11:18:42.253 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: CISQMSData::INFO: NET make_holepunching_msg HTYPE_KEEP_ALIVE pszTemp2=;0;1;192.168.40.112;255.255.255.0;192.168.40.1;211.44.250.88;121.125.25.110 08-23 11:18:42.253 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: CISQMSData::INFO: NET make_holepunching_msg HTYPE_KEEP_ALIVE this->szSTBIP=192.168.40.112 08-23 11:18:42.253 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: CISQMSComm::send_data_to_hp_server m_HPServerInfo.nPort = 30812 pData = [SEQ=68 08-23 11:18:42.253 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: T=KEEP-ALIVE 08-23 11:18:42.253 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: STB_ID=E13FB3EC-EDA0-11EA-BD09-5F5CE34E524C 08-23 11:18:42.253 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: STB_MAC=001238000938 08-23 11:18:42.253 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: STB_IP=192.168.40.112 08-23 11:18:42.253 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: ] 08-23 11:18:42.253 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: send_data_to_hp_server already connected nSockID(16) - write return : 107 08-23 11:18:42.253 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: CISQMSData::destory_holepunching_msg 08-23 11:18:42.254 audioserver(362) 3983 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.255 audioserver(362) 3983 I chatty : uid=1041(audioserver) Binder:362_8 identical 1 line 08-23 11:18:42.257 audioserver(362) 3983 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.258 mediaserver(420) 19295 I CCodec : Created component [c2.android.vorbis.decoder] 08-23 11:18:42.258 audioserver(362) 3983 D AudioFlinger: Client defaulted notificationFrames to 196 for frameCount 392 08-23 11:18:42.259 mediaserver(420) 19295 D CCodecConfig: read media type: audio/vorbis 08-23 11:18:42.260 audioserver(362) 3983 D AF: :TrackHandle: OpPlayAudio: track:63 usage:18 not muted 08-23 11:18:42.265 com.skt.aidev.nugustb(18133) 19215 W AudioTrack: Use of stream types is deprecated for operations other than volume control 08-23 11:18:42.265 com.skt.aidev.nugustb(18133) 19215 W AudioTrack: See the documentation of AudioTrack() for what to use instead with android.media.AudioAttributes to qualify your playback use case 08-23 11:18:42.266 audioserver(362) 3983 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.266 audioserver(362) 3983 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.266 audioserver(362) 3983 I APM_AudioPolicyManager: [checkAndSetVolume] salmon outputDesc->setVolume (6065), volumeDb:-44.244999, device(525312) 08-23 11:18:42.266 audioserver(362) 3983 I APM: :AudioOutputDescriptor: setVolume: salmon SwAudioOutputDescriptor::setVolume!! 08-23 11:18:42.266 audioserver(362) 3983 I APM: :AudioOutputDescriptor: setVolume: salmon !AudioOutputDescriptor::setVolume return false!! 08-23 11:18:42.266 audioserver(362) 3983 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.266 audioserver(362) 3983 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.266 audioserver(362) 3983 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 6, device 2 08-23 11:18:42.266 audioserver(362) 3983 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 8, device 400 08-23 11:18:42.266 audioserver(362) 3983 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 8, device 400 08-23 11:18:42.273 mediaserver(420) 19295 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.max-count.values 08-23 11:18:42.273 mediaserver(420) 19295 D ReflectedParamUpdater: extent() != 1 for single value type: output.subscribed-indices.values 08-23 11:18:42.273 mediaserver(420) 19295 D ReflectedParamUpdater: extent() != 1 for single value type: input.buffers.allocator-ids.values 08-23 11:18:42.274 mediaserver(420) 19295 D ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.allocator-ids.values 08-23 11:18:42.274 mediaserver(420) 19295 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.allocator-ids.values 08-23 11:18:42.274 mediaserver(420) 19295 D ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.pool-ids.values 08-23 11:18:42.274 mediaserver(420) 19295 D ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.pool-ids.values 08-23 11:18:42.277 mediaserver(420) 19295 I CCodecConfig: query failed after returning 7 values (BAD_INDEX) 08-23 11:18:42.277 mediaserver(420) 19295 D CCodecConfig: c2 config is Dict { 08-23 11:18:42.277 mediaserver(420) 19295 D CCodecConfig: c2::u32 coded.bitrate.value = 64000 08-23 11:18:42.277 mediaserver(420) 19295 D CCodecConfig: c2::u32 input.buffers.max-size.value = 32768 08-23 11:18:42.277 mediaserver(420) 19295 D CCodecConfig: c2::u32 input.delay.value = 0 08-23 11:18:42.277 mediaserver(420) 19295 D CCodecConfig: string input.media-type.value = "audio/vorbis" 08-23 11:18:42.277 mediaserver(420) 19295 D CCodecConfig: string output.media-type.value = "audio/raw" 08-23 11:18:42.277 mediaserver(420) 19295 D CCodecConfig: c2::u32 raw.channel-count.value = 1 08-23 11:18:42.277 mediaserver(420) 19295 D CCodecConfig: c2::u32 raw.sample-rate.value = 48000 08-23 11:18:42.277 mediaserver(420) 19295 D CCodecConfig: } 08-23 11:18:42.280 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:18:42.281 mediaserver(420) 19295 D CCodecConfig: no c2 equivalents for durationUs 08-23 11:18:42.281 mediaserver(420) 19295 D CCodecConfig: no c2 equivalents for csd-1 08-23 11:18:42.281 mediaserver(420) 19295 D CCodecConfig: no c2 equivalents for channel-mask 08-23 11:18:42.285 ledd(754) - I FXC_STUB_LP50XX: lp50xx_set_status_led, 3532, return_no_thread 08-23 11:18:42.286 mediaserver(420) 19295 D CCodecConfig: c2 config is Dict { 08-23 11:18:42.286 mediaserver(420) 19295 D CCodecConfig: c2::u32 coded.bitrate.value = 64000 08-23 11:18:42.286 mediaserver(420) 19295 D CCodecConfig: c2::u32 input.buffers.max-size.value = 32768 08-23 11:18:42.286 mediaserver(420) 19295 D CCodecConfig: c2::u32 input.delay.value = 0 08-23 11:18:42.286 mediaserver(420) 19295 D CCodecConfig: string input.media-type.value = "audio/vorbis" 08-23 11:18:42.286 mediaserver(420) 19295 D CCodecConfig: string output.media-type.value = "audio/raw" 08-23 11:18:42.286 mediaserver(420) 19295 D CCodecConfig: c2::u32 raw.channel-count.value = 1 08-23 11:18:42.286 mediaserver(420) 19295 D CCodecConfig: c2::u32 raw.sample-rate.value = 22050 08-23 11:18:42.286 mediaserver(420) 19295 D CCodecConfig: } 08-23 11:18:42.288 mediaserver(420) 19295 W Codec2Client: query -- param skipped: index = 1107298332. 08-23 11:18:42.288 mediaserver(420) 19295 D CCodec : setup formats input: AMessage(what = 0x00000000) = { 08-23 11:18:42.288 mediaserver(420) 19295 D CCodec : int32_t channel-count = 1 08-23 11:18:42.288 mediaserver(420) 19295 D CCodec : int32_t max-input-size = 32768 08-23 11:18:42.288 mediaserver(420) 19295 D CCodec : string mime = "audio/vorbis" 08-23 11:18:42.288 mediaserver(420) 19295 D CCodec : int32_t sample-rate = 22050 08-23 11:18:42.288 mediaserver(420) 19295 D CCodec : } and output: AMessage(what = 0x00000000) = { 08-23 11:18:42.288 mediaserver(420) 19295 D CCodec : int32_t channel-count = 1 08-23 11:18:42.288 mediaserver(420) 19295 D CCodec : string mime = "audio/raw" 08-23 11:18:42.288 mediaserver(420) 19295 D CCodec : int32_t sample-rate = 22050 08-23 11:18:42.288 mediaserver(420) 19295 D CCodec : int32_t channel-mask = 1 08-23 11:18:42.288 mediaserver(420) 19295 D CCodec : } 08-23 11:18:42.288 mediaserver(420) 19295 I MediaCodec: MediaCodec will operate in async mode 08-23 11:18:42.289 mediaserver(420) 19295 W Codec2Client: query -- param skipped: index = 1342179345. 08-23 11:18:42.289 mediaserver(420) 19295 W Codec2Client: query -- param skipped: index = 2415921170. 08-23 11:18:42.291 mediaserver(420) 19295 D CCodecBufferChannel: [c2.android.vorbis.decoder#168] Created input block pool with allocatorID 16 => poolID 20 - OK (0) 08-23 11:18:42.292 media.swcodec(438) 1864 D BufferPoolAccessor: bufferpool2 0xe9200318 : 0(0 size) total buffers - 0(0 size) used buffers - 507/513 (recycle/alloc) - 6/512 (fetch/transfer) 08-23 11:18:42.292 media.swcodec(438) 1864 D BufferPoolAccessor: Destruction - bufferpool2 0xe9200318 cached: 0/0M, 0/0% in use; allocs: 513, 99% recycled; transfers: 512, 99% unfetced 08-23 11:18:42.293 com.skt.aidev.nugustb(18133) 19258 D BufferPoolAccessor: bufferpool2 0xc4e4f010 : 0(0 size) total buffers - 0(0 size) used buffers - 1/9 (recycle/alloc) - 8/134 (fetch/transfer) 08-23 11:18:42.293 com.skt.aidev.nugustb(18133) 19258 D BufferPoolAccessor: Destruction - bufferpool2 0xc4e4f010 cached: 0/0M, 0/0% in use; allocs: 9, 11% recycled; transfers: 134, 94% unfetced 08-23 11:18:42.293 mediaserver(420) 19295 I CCodecBufferChannel: [c2.android.vorbis.decoder#168] Created output block pool with allocatorID 16 => poolID 27 - OK 08-23 11:18:42.294 mediaserver(420) 19295 D CCodecBufferChannel: [c2.android.vorbis.decoder#168] Configured output block pool ids 27 => OK 08-23 11:18:42.294 media.swcodec(438) 19296 D SimpleC2Component: Using output block pool with poolID 27 => got 27 - 0 08-23 11:18:42.306 audioserver(362) 3983 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.306 audioserver(362) 2134 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.308 audioserver(362) 2134 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.308 audioserver(362) 2134 W AudioFlinger: createTrack_l(): mismatch between requested flags (00000008) and output flags (00000002) 08-23 11:18:42.313 audioserver(362) 2134 D AudioFlinger: Client defaulted notificationFrames to 5554 for frameCount 11109 08-23 11:18:42.314 audioserver(362) 2134 D AF: :TrackHandle: OpPlayAudio: track:64 usage:17 not muted 08-23 11:18:42.322 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.322 audioserver(362) 4716 I chatty : uid=1041(audioserver) Binder:362_A identical 1 line 08-23 11:18:42.322 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.322 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 6, device 2 08-23 11:18:42.322 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.322 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.322 audioserver(362) 4716 I APM_AudioPolicyManager: [checkAndSetVolume] salmon outputDesc->setVolume (6065), volumeDb:-44.244999, device(525312) 08-23 11:18:42.322 audioserver(362) 4716 I APM: :AudioOutputDescriptor: setVolume: salmon SwAudioOutputDescriptor::setVolume!! 08-23 11:18:42.322 audioserver(362) 4716 I APM: :AudioOutputDescriptor: setVolume: salmon !AudioOutputDescriptor::setVolume return false!! 08-23 11:18:42.322 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.322 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.322 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 6, device 2 08-23 11:18:42.322 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 8, device 400 08-23 11:18:42.322 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 8, device 400 08-23 11:18:42.346 audioserver(362) 625 D AudioFlinger: mixer(0xe46be080) throttle end: throttle time(39) 08-23 11:18:42.354 mediaserver(420) 19293 D AudioTrack: getTimestamp_l(45): device stall time corrected using current time 4212612357320 08-23 11:18:42.406 system_server(681) 1645 D AS.AudioService: MSG_NUGU_START_NOTI : 08-23 11:18:42.407 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 796: adev=0xed74a000 kvpairs=nugu_playback=on 08-23 11:18:42.407 android.hardware.audio@2.0-service(341) 436 I audio_hw: JIMDBG adev_set_parameters:797, adev=0xed74a000 kvpairs=nugu_playback=on 08-23 11:18:42.407 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 1262: Set NUGU Playback on 08-23 11:18:42.408 android.hardware.audio@2.0-service(341) 436 D audio_device_util: handleAudioRouting() line 1118: handleAudioRouting enter 08-23 11:18:42.408 android.hardware.audio@2.0-service(341) 436 D audio_device_util: setBTAudioMute() line 812: ahal_dbg (812), mute(1) 08-23 11:18:42.408 android.hardware.audio@2.0-service(341) 436 D vendor_hal_audio: VENDOR_HAL_AUDIO_SetMute() line 354: VENDOR_HAL_AUDIO_SetMute path=4 mute=1 08-23 11:18:42.408 android.hardware.audio@2.0-service(341) 436 I vendor_hal_audio: VENDOR_HAL_AUDIO_SetMute:385, path=AMP_SND BT output, mute=1 08-23 11:18:42.410 android.hardware.audio@2.0-service(341) 436 D audio_device_util: setBtvAudioMute() line 345: ahal_dbg (345), m(1), r(-1) 08-23 11:18:42.411 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [GLOBAL MUTE][externFunctionStateChangeCheckForGlobalMuteThread] event->name, btv_muted @@INFO:BtvMediaPlayerService.cpp(2085) 08-23 11:18:42.411 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [GLOBAL MUTE][externFunctionStateChangeCheckForGlobalMuteThread] IN_CREATE @@INFO:BtvMediaPlayerService.cpp(2088) 08-23 11:18:42.411 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: BTF\|changeGlobalAudioMute_l\|4853\|IN\| 08-23 11:18:42.411 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: @@INFO:IptvSocPlayer.cpp(4853) 08-23 11:18:42.411 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [INFO] AFTER MUTEX @@INFO:IptvSocPlayer.cpp(4855) 08-23 11:18:42.411 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] ######################################################### 08-23 11:18:42.411 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] START 08-23 11:18:42.411 com.skb.btvservice@1.0-service(377) 664 W btvSocHalService-JNI: btvSocHalService_GetAudioMute::474 : in 08-23 11:18:42.411 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_GetAudioMute\|732\|IN\| player=e99a6000 08-23 11:18:42.411 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_GetAudioMute\|743\| mute:0 08-23 11:18:42.411 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_GetAudioMute\|747\|OUT\| 08-23 11:18:42.411 com.skb.btvservice@1.0-service(377) 664 D btvSocHalService-JNI: [operator()] mute : 0, ret : 0 08-23 11:18:42.411 com.skb.btvservice@1.0-service(377) 664 W btvSocHalService-JNI: btvSocHalService_GetAudioMute::482 : out 08-23 11:18:42.411 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] ++ enableGlobalMute !!!! 08-23 11:18:42.411 com.skb.btvservice@1.0-service(377) 664 W btvSocHalService-JNI: btvSocHalService_SetAudioMute::467 : in 08-23 11:18:42.411 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_SetAudioMute\|693\|IN\| player=e99a6000, mute=1 08-23 11:18:42.412 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: ++AVP_SetAudioMute[0] mute audio 08-23 11:18:42.412 com.skb.btvsochalservice@1.0-service(379) - E [ 4212.669] [35;1mAmp1@@[MODULE_SNDSRV: AMP_SND_SetStreamMute():3513]:[0m : SNDAPI tony restore volume even for HDMI passthrough ! restore volume target:[0] 08-23 11:18:42.412 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_SetAudioMute\|726\|OUT\| 08-23 11:18:42.412 com.skb.btvservice@1.0-service(377) 664 W btvSocHalService-JNI: btvSocHalService_SetAudioMute::470 : out, opStatus:0 08-23 11:18:42.412 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] ++ mute audio 08-23 11:18:42.412 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] END [0] 08-23 11:18:42.413 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] ######################################################### 08-23 11:18:42.413 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [changeGlobalAudioMute_l] called, mute : 1 @@INFO:IptvSocPlayer.cpp(4869) 08-23 11:18:42.413 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: BTF\|changeGlobalAudioMute_l\|4870\|OUT\| 08-23 11:18:42.413 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: @@INFO:IptvSocPlayer.cpp(4870) 08-23 11:18:42.413 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: BTF\|changeGlobalAudioMute_l\|4853\|IN\| 08-23 11:18:42.413 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: @@INFO:IptvSocPlayer.cpp(4853) 08-23 11:18:42.413 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [INFO] AFTER MUTEX @@INFO:IptvSocPlayer.cpp(4855) 08-23 11:18:42.413 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [changeGlobalAudioMute_l] called, mute : 1 @@INFO:IptvSocPlayer.cpp(4862) 08-23 11:18:42.413 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [WARNING] DEVICE IS CLOSED. WILL RETURN OK @@INFO:IptvSocPlayer.cpp(4863) 08-23 11:18:42.413 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [GLOBAL MUTE][externFunctionStateChangeCheckForGlobalMuteThread] read @@INFO:BtvMediaPlayerService.cpp(2073) 08-23 11:18:42.418 audioserver(362) 625 D AudioFlinger: mixer(0xe46be080) throttle end: throttle time(11) 08-23 11:18:42.421 android.hardware.audio@2.0-service(341) 436 I audio_device_util: Delay 10000 us to notify aux status 08-23 11:18:42.424 android.hardware.audio@2.0-service(341) 436 D audio_device_util: handleAudioRouting() line 1148: Case for Nugu playback. but don't care others 08-23 11:18:42.425 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:42.425 system_server(681) 1645 D AS.AudioService: getLastAudibleStreamVolume = 10, streamType=12, device=524288 08-23 11:18:42.426 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 796: adev=0xed74a000 kvpairs=btv_volume_index=10 08-23 11:18:42.426 android.hardware.audio@2.0-service(341) 436 I audio_hw: JIMDBG adev_set_parameters:797, adev=0xed74a000 kvpairs=btv_volume_index=10 08-23 11:18:42.426 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 796: adev=0xed74a000 kvpairs=btv_audio_device=speaker 08-23 11:18:42.426 android.hardware.audio@2.0-service(341) 436 I audio_hw: JIMDBG adev_set_parameters:797, adev=0xed74a000 kvpairs=btv_audio_device=speaker 08-23 11:18:42.427 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 796: adev=0xed74a000 kvpairs=nugu_amp=on 08-23 11:18:42.427 android.hardware.audio@2.0-service(341) 436 I audio_hw: JIMDBG adev_set_parameters:797, adev=0xed74a000 kvpairs=nugu_amp=on 08-23 11:18:42.427 android.hardware.audio@2.0-service(341) 436 I audio_hw: [charmk]===============> NUGU AMP ON 08-23 11:18:42.437 android.hardware.audio@2.0-service(341) 18234 D MPP-PREPROCESS: [Tyche_MC_PreProc_Run] [CHANGE] SPK_MODE=1, SPK_VOL=10 08-23 11:18:42.772 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=89, crc=8d 66 f9 0b @@INFO:mcas-port-impl.c(739) 08-23 11:18:42.772 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:42.772 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=89, crc=8d 66 f9 0b @@INFO:mcas-port-impl.c(739) 08-23 11:18:42.772 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:42.789 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:42.789 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:43.007 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 44744 08-23 11:18:43.207 system_server(681) 1645 D AS.AudioService: MSG_NUGU_UNMUTE_CHECK 08-23 11:18:43.207 system_server(681) 1645 D AS.AudioService: STREAM_NUGU stream Active recheck MSG_NUGU_UNMUTE_CHECK 08-23 11:18:43.789 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:43.790 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:44.006 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 60724 08-23 11:18:44.145 audioserver(362) 625 D AF: :Track: interceptBuffer: took 267us to intercept 0 tracks 08-23 11:18:44.208 system_server(681) 1645 D AS.AudioService: MSG_NUGU_UNMUTE_CHECK 08-23 11:18:44.209 system_server(681) 1645 D AS.AudioService: STREAM_NUGU stream Active recheck MSG_NUGU_UNMUTE_CHECK 08-23 11:18:44.590 audioserver(362) 625 D AudioFlinger: mixer(0xe46be080) throttle end: throttle time(7) 08-23 11:18:44.794 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:44.796 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:45.006 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 47376 08-23 11:18:45.212 system_server(681) 1645 D AS.AudioService: MSG_NUGU_UNMUTE_CHECK 08-23 11:18:45.213 system_server(681) 1645 D AS.AudioService: STREAM_NUGU stream Active recheck MSG_NUGU_UNMUTE_CHECK 08-23 11:18:45.794 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:45.794 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:45.837 audioserver(362) 625 D AudioFlinger: mixer(0xe46be080) throttle end: throttle time(2) 08-23 11:18:46.013 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 54896 08-23 11:18:46.214 system_server(681) 1645 D AS.AudioService: MSG_NUGU_UNMUTE_CHECK 08-23 11:18:46.218 system_server(681) 1645 D AS.AudioService: STREAM_NUGU stream Active recheck MSG_NUGU_UNMUTE_CHECK 08-23 11:18:46.794 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:46.795 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:47.005 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 54896 08-23 11:18:47.219 system_server(681) 1645 D AS.AudioService: MSG_NUGU_UNMUTE_CHECK 08-23 11:18:47.223 system_server(681) 1645 D AS.AudioService: STREAM_NUGU stream Active recheck MSG_NUGU_UNMUTE_CHECK 08-23 11:18:47.282 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:18:47.794 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:47.795 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:47.830 mediaserver(420) 19295 D BufferPoolAccessor: bufferpool2 0xea36eb10 : 11(360448 size) total buffers - 8(262144 size) used buffers - 354/365 (recycle/alloc) - 11/726 (fetch/transfer) 08-23 11:18:47.832 media.swcodec(438) 19296 D BufferPoolAccessor: bufferpool2 0xe9200818 : 6(98304 size) total buffers - 1(16384 size) used buffers - 356/362 (recycle/alloc) - 6/360 (fetch/transfer) 08-23 11:18:48.019 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 48880 08-23 11:18:48.224 system_server(681) 1645 D AS.AudioService: MSG_NUGU_UNMUTE_CHECK 08-23 11:18:48.225 system_server(681) 1645 D AS.AudioService: STREAM_NUGU stream Active recheck MSG_NUGU_UNMUTE_CHECK 08-23 11:18:48.781 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=106, crc=c9 1a 8f 7f @@INFO:mcas-port-impl.c(739) 08-23 11:18:48.781 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:48.781 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=106, crc=c9 1a 8f 7f @@INFO:mcas-port-impl.c(739) 08-23 11:18:48.781 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:48.794 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:48.794 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:49.013 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 54144 08-23 11:18:49.226 system_server(681) 1645 D AS.AudioService: MSG_NUGU_UNMUTE_CHECK 08-23 11:18:49.227 system_server(681) 1645 D AS.AudioService: STREAM_NUGU stream Active recheck MSG_NUGU_UNMUTE_CHECK 08-23 11:18:49.416 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:18:49.417 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:18:49.420 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:18:49.421 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:18:49.431 com.skb.tv(2755) - I TvNotificationListenerService: onNotificationPosted() 08-23 11:18:49.444 com.skb.tv(2755) 13254 I TvNotificationManager: getNotificationCount() count : 1 08-23 11:18:49.794 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:49.795 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:49.902 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:grpc_manager_impl.cc(852)] GRPC[uri=localhost:10042]: num_pending_calls=0, num_active_handlers=1, num_free_handlers=0 08-23 11:18:49.903 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:grpc_manager_impl.cc(852)] GRPC[uri=localhost:10042]: num_pending_calls=0, num_active_handlers=1, num_free_handlers=0 08-23 11:18:49.923 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:service_base.h(78)] Size of the map for type AuthTokenResponse is 0 08-23 11:18:50.013 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 30080 08-23 11:18:50.094 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:grpc_manager_impl.cc(852)] GRPC[uri=]: num_pending_calls=0, num_active_handlers=0, num_free_handlers=0 08-23 11:18:50.100 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:grpc_manager_impl.cc(852)] GRPC[uri=]: num_pending_calls=0, num_active_handlers=0, num_free_handlers=0 08-23 11:18:50.122 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:service_base.h(246)] Container sizes for type CloudSetting are (session_info_map: 0), (topic_info_map: 21), (pending_events: 0). 08-23 11:18:50.156 com.google.android.apps.mediashell(4508) 8554 I chromium: [4508:8554:INFO:grpc_manager_impl.cc(852)] GRPC[uri=]: num_pending_calls=0, num_active_handlers=0, num_free_handlers=0 08-23 11:18:50.228 system_server(681) 1645 D AS.AudioService: MSG_NUGU_UNMUTE_CHECK 08-23 11:18:50.229 system_server(681) 1645 D AS.AudioService: STREAM_NUGU stream Active recheck MSG_NUGU_UNMUTE_CHECK 08-23 11:18:50.254 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: CISQMSMain::is_time_to_send_data count(1020) 08-23 11:18:50.386 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:grpc_manager_impl.cc(852)] GRPC[uri=[::]:0]: num_pending_calls=0, num_active_handlers=3, num_free_handlers=0 08-23 11:18:50.561 mediaserver(420) 19295 D CCodecBufferChannel: [c2.android.vorbis.decoder#168] buffers after EOS ignored (0 us) 08-23 11:18:50.561 mediaserver(420) 19295 I chatty : uid=1013(media) NPDecoder-CL identical 1 line 08-23 11:18:50.562 mediaserver(420) 19295 D CCodecBufferChannel: [c2.android.vorbis.decoder#168] buffers after EOS ignored (0 us) 08-23 11:18:50.564 mediaserver(420) 19293 D AudioTrack: stop(45): called with 191990 frames delivered 08-23 11:18:50.622 skb_logsystem(2232) 4055 D iSQMSAgent_LogSystem: CISQMSSystemInfo::check_system_status 0x0001 08-23 11:18:50.622 skb_logsystem(2232) 4055 D iSQMSAgent_LogSystem: CISQMSEnvm get_system_info - eType : 0 08-23 11:18:50.623 skb_logsystem(2232) 4055 D iSQMSAgent_LogSystem: cpu_usage=41 idle=13641 total=22879 08-23 11:18:50.623 skb_logsystem(2232) 4055 D iSQMSAgent_LogSystem: get_system_memory MEM total= 1821236 free= 46980 buffers =476724 cached=3552 current=68 08-23 11:18:50.623 skb_logsystem(2232) 4055 D iSQMSAgent_LogSystem: CISQMSEnvm get_system_info - eType : 1 08-23 11:18:50.623 skb_logsystem(2232) 4055 D iSQMSAgent_LogSystem: CISQMSSystemInfo::MEM Check memInfo.currentMem =68, limitMem=95 08-23 11:18:50.728 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:grpc_manager_impl.cc(852)] GRPC[uri=]: num_pending_calls=0, num_active_handlers=0, num_free_handlers=0 08-23 11:18:50.728 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:grpc_manager_impl.cc(852)] GRPC[uri=]: num_pending_calls=0, num_active_handlers=0, num_free_handlers=0 08-23 11:18:50.733 com.google.android.apps.mediashell(4508) 8581 I chromium: [4508:8581:INFO:grpc_manager_impl.cc(852)] GRPC[uri=binder:com.google.android.apps.mediashell:usonia-ipc-socket]: num_pending_calls=0, num_active_handlers=0, num_free_handlers=0 08-23 11:18:50.734 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:grpc_manager_impl.cc(852)] GRPC[uri=]: num_pending_calls=0, num_active_handlers=0, num_free_handlers=0 08-23 11:18:50.734 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:grpc_manager_impl.cc(852)] GRPC[uri=]: num_pending_calls=0, num_active_handlers=0, num_free_handlers=0 08-23 11:18:50.751 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:service_base.h(78)] Size of the map for type LocalDeviceInfo is 0 08-23 11:18:50.751 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:service_base.h(78)] Size of the map for type NetworkInfo is 0 08-23 11:18:50.795 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:50.795 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:51.012 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 59032 08-23 11:18:51.166 com.skt.aidev.nugustb(18133) - V MediaPlayer: resetDrmState: mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false 08-23 11:18:51.166 com.skt.aidev.nugustb(18133) - V MediaPlayer: cleanDrmObj: mDrmObj=null mDrmSessionId=null 08-23 11:18:51.166 mediaserver(420) 3471 D NuPlayerDriver: reset(0xe5b33500) at state 6 08-23 11:18:51.171 media.swcodec(438) 1944 V C2Store : in ~ComponentModule 08-23 11:18:51.171 media.swcodec(438) 1944 V C2Store : unloading dll 08-23 11:18:51.171 mediaserver(420) 19290 D NuPlayerDriver: notifyResetComplete(0xe5b33500) 08-23 11:18:51.172 mediaserver(420) 3471 W AMessage: failed to post message as target looper for handler 0 is gone. 08-23 11:18:51.173 com.skt.aidev.nugustb(18133) - W MediaPlayer: mediaplayer went away with unhandled events 08-23 11:18:51.179 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:51.180 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:51.180 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 6, device 2 08-23 11:18:51.180 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:51.180 audioserver(362) 522 I chatty : uid=1041(audioserver) ApmOutput identical 2 lines 08-23 11:18:51.180 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:51.180 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 6, device 2 08-23 11:18:51.180 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 8, device 400 08-23 11:18:51.180 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 8, device 400 08-23 11:18:51.180 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:51.188 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:metrics_stat_logger.cc(55)] Metrics stat for the past 60 secs : total=0 08-23 11:18:51.230 system_server(681) 1645 D AS.AudioService: MSG_NUGU_UNMUTE_CHECK 08-23 11:18:51.230 system_server(681) 1645 D AS.AudioService: STREAM_NUGU stream Active recheck MSG_NUGU_UNMUTE_CHECK 08-23 11:18:51.667 system_server(681) 2922 I MediaFocusControl: abandonAudioFocus() from uid/pid 10028/18133 clientId=android.media.AudioManager@f97d8d5m7.b@17668ea 08-23 11:18:51.668 com.skb.tv(2755) - D AudioManager: dispatching onAudioFocusChange(1) to android.media.AudioManager@839d7d6com.skb.google.tv.global.-$$Lambda$AudioFocusManager$JmF9i4PTPURJn676IPzdIL4WBms@5e84a57 08-23 11:18:51.668 com.skb.tv(2755) - D AudioFocusManager: mAudioFocusChangeListener.onAudioFocusChange() AUDIOFOCUS_REQUEST_GRANTED 08-23 11:18:51.668 com.skt.aidev.nugustb(18133) 18207 D AudioTrack: stop(44): called with 76040 frames delivered 08-23 11:18:51.679 system_server(681) 2922 I MediaFocusControl: abandonAudioFocus() from uid/pid 10028/18133 clientId=android.media.AudioManager@f97d8d5m7.b@17668ea 08-23 11:18:51.797 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:51.798 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:51.850 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:51.850 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 6, device 2 08-23 11:18:51.850 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:51.851 audioserver(362) 522 I chatty : uid=1041(audioserver) ApmOutput identical 2 lines 08-23 11:18:51.851 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:51.851 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 6, device 2 08-23 11:18:51.851 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 8, device 400 08-23 11:18:51.851 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 8, device 400 08-23 11:18:51.851 audioserver(362) 522 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:52.003 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 59032 08-23 11:18:52.232 system_server(681) 1645 D AS.AudioService: MSG_NUGU_UNMUTE_CHECK 08-23 11:18:52.232 system_server(681) 1645 D AS.AudioService: STREAM_NUGU stream no Active !! mSetupWizard : falsemsg.arg1 : 0 08-23 11:18:52.232 system_server(681) 1645 D AS.AudioService: setNuguEndFlag() : 08-23 11:18:52.232 audioserver(362) 4716 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream: 3 index : 1000 device: 524288 : 08-23 11:18:52.232 system_server(681) 1645 D AS.AudioService: nugu_playback=off 08-23 11:18:52.233 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 796: adev=0xed74a000 kvpairs=nugu_playback=off 08-23 11:18:52.233 android.hardware.audio@2.0-service(341) 436 I audio_hw: JIMDBG adev_set_parameters:797, adev=0xed74a000 kvpairs=nugu_playback=off 08-23 11:18:52.233 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 1305: Set NUGU Playback off 08-23 11:18:52.249 com.skt.aidev.nugustb(18133) 18236 I libTycheWakeup: Wakeup health check (2): feat.cnt=45000, pwr=2500.000000, score=-0.988015 08-23 11:18:52.260 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 1324: change to Spdif format to (5). 08-23 11:18:52.267 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 1330: change to HDMI format to (2). 08-23 11:18:52.267 android.hardware.audio@2.0-service(341) 436 D audio_device_util: handleAudioRouting() line 1118: handleAudioRouting enter 08-23 11:18:52.267 android.hardware.audio@2.0-service(341) 436 D audio_device_util: setBtvAudioMute() line 354: ahal_dbg (354), m(0), r(0) 08-23 11:18:52.267 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [GLOBAL MUTE][externFunctionStateChangeCheckForGlobalMuteThread] event->name, btv_muted @@INFO:BtvMediaPlayerService.cpp(2085) 08-23 11:18:52.267 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [GLOBAL MUTE][externFunctionStateChangeCheckForGlobalMuteThread] IN_DELETE @@INFO:BtvMediaPlayerService.cpp(2089) 08-23 11:18:52.267 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: BTF\|changeGlobalAudioMute_l\|4853\|IN\| 08-23 11:18:52.267 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: @@INFO:IptvSocPlayer.cpp(4853) 08-23 11:18:52.267 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [INFO] AFTER MUTEX @@INFO:IptvSocPlayer.cpp(4855) 08-23 11:18:52.267 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] ######################################################### 08-23 11:18:52.267 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] START 08-23 11:18:52.267 com.skb.btvservice@1.0-service(377) 664 W btvSocHalService-JNI: btvSocHalService_GetAudioMute::474 : in 08-23 11:18:52.267 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_GetAudioMute\|732\|IN\| player=e99a6000 08-23 11:18:52.267 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_GetAudioMute\|743\| mute:1 08-23 11:18:52.267 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_GetAudioMute\|747\|OUT\| 08-23 11:18:52.267 com.skb.btvservice@1.0-service(377) 664 D btvSocHalService-JNI: [operator()] mute : 1, ret : 0 08-23 11:18:52.267 com.skb.btvservice@1.0-service(377) 664 W btvSocHalService-JNI: btvSocHalService_GetAudioMute::482 : out 08-23 11:18:52.267 com.skb.btvservice@1.0-service(377) 664 W btvSocHalService-JNI: btvSocHalService_SetAudioMute::467 : in 08-23 11:18:52.267 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_SetAudioMute\|693\|IN\| player=e99a6000, mute=0 08-23 11:18:52.268 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: ++AVP_SetAudioMute[0] unmute audio 08-23 11:18:52.268 com.skb.btvsochalservice@1.0-service(379) - E [ 4222.525] [35;1mAmp1@@[MODULE_SNDSRV: AMP_SND_SetStreamMute():3513]:[0m : SNDAPI tony restore volume even for HDMI passthrough ! restore volume target:[0] 08-23 11:18:52.269 com.skb.btvsochalservice@1.0-service(379) - D halMediaPlayer: BTF\|AVP_SetAudioMute\|726\|OUT\| 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 W btvSocHalService-JNI: btvSocHalService_SetAudioMute::470 : out, opStatus:0 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] ++ unmute audio 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] END [0] 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-STDVB: [DEVICE 0][ST_ChangeGlobalAudioMute] ######################################################### 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [changeGlobalAudioMute_l] called, mute : 0 @@INFO:IptvSocPlayer.cpp(4869) 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: BTF\|changeGlobalAudioMute_l\|4870\|OUT\| 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: @@INFO:IptvSocPlayer.cpp(4870) 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: BTF\|changeGlobalAudioMute_l\|4853\|IN\| 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: @@INFO:IptvSocPlayer.cpp(4853) 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [INFO] AFTER MUTEX @@INFO:IptvSocPlayer.cpp(4855) 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [changeGlobalAudioMute_l] called, mute : 0 @@INFO:IptvSocPlayer.cpp(4862) 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [WARNING] DEVICE IS CLOSED. WILL RETURN OK @@INFO:IptvSocPlayer.cpp(4863) 08-23 11:18:52.269 com.skb.btvservice@1.0-service(377) 664 D BOXKEY-MEDIA: [GLOBAL MUTE][externFunctionStateChangeCheckForGlobalMuteThread] read @@INFO:BtvMediaPlayerService.cpp(2073) 08-23 11:18:52.277 android.hardware.audio@2.0-service(341) 436 I audio_device_util: Delay 10000 us to notify aux status 08-23 11:18:52.277 android.hardware.audio@2.0-service(341) 436 D audio_device_util: handleAudioRouting() line 1396: Case for No thing connected 08-23 11:18:52.279 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 796: adev=0xed74a000 kvpairs=btv_audio_device=speaker 08-23 11:18:52.279 android.hardware.audio@2.0-service(341) 436 I audio_hw: JIMDBG adev_set_parameters:797, adev=0xed74a000 kvpairs=btv_audio_device=speaker 08-23 11:18:52.281 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:18:52.281 com.android.systemui(1731) 1925 D vol.VolumeDialogControl: onVolumeChangedW stream =3, showUI=true, fromKey=true, showNuguNoti=true 08-23 11:18:52.282 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:52.282 system_server(681) 2922 D AS.AudioService: getLastAudibleStreamVolume = 0, streamType=3, device=524288 08-23 11:18:52.283 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:52.283 com.android.systemui(1731) 1925 D vol.VolumeDialogControl: onVolumeChangedW changed :true 08-23 11:18:52.284 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: updateVolumeRowH() row.stream=3, row.showUI=true, row.removeMute=false 08-23 11:18:52.284 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: updateVolumeRowH() ss.muted=false, vlevel=0 08-23 11:18:52.284 com.android.systemui(1731) 1925 I vol.Events: writeEvent key STREAM_MUSIC 0 08-23 11:18:52.284 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: showVolumeZeroLevel visible: true 08-23 11:18:52.285 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: BTDeviceConnected() bConnected:false 08-23 11:18:52.285 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: isFamiltyEarphoneMode() 08-23 11:18:52.285 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: showH r=volume_changed, volZeroIconShowing:true, muteIconShowing:false 08-23 11:18:52.285 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: showH r=volume_changed, volZeroIconShowing_Nugu:false, muteIconShowing_Nugu:false 08-23 11:18:52.285 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: showH volumeUIUpdatepass : false 08-23 11:18:52.285 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: showH mActiveStream : 3 08-23 11:18:52.285 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: computeTimeoutH 08-23 11:18:52.287 com.android.systemui(1731) - I vol.Events: writeEvent show_dialog volume_changed keyguard=false 08-23 11:18:52.289 system_server(681) 2922 D AS.AudioService: Volume controller visible: true 08-23 11:18:52.480 system_server(681) 1645 D AS.AudioService: MSG_NUGU_START_NOTI : 08-23 11:18:52.480 system_server(681) 1645 D AS.AudioService: setBtvUnmute start 08-23 11:18:52.481 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:52.481 system_server(681) 1645 D AS.AudioService: getLastAudibleStreamVolume = 0, streamType=3, device=524288 08-23 11:18:52.481 system_server(681) 1645 D AS.AudioService: setBtvUnmute STREAM_MUSIC sucess btv_oldindex =0mAvrcpAbsVolSupported= false 08-23 11:18:52.481 system_server(681) 1645 D AS.AudioService: setBtvUnmute STREAM_MUSIC btv_oldindex : 0 08-23 11:18:52.481 audioserver(362) 4716 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream: 3 index : 0 device: 524288 : 08-23 11:18:52.481 audioserver(362) 4716 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume mExtMute: 0, mExtCC: 0 08-23 11:18:52.481 audioserver(362) 4716 I APM_AudioPolicyManager: setStreamVolumeIndex: set_btv_media_volume stream : 3 volume_index : 0 gain : -758.000000 08-23 11:18:52.481 audioserver(362) 4716 D BtvMediaVolumeControlLib: btvMediaPlayerVolumeControl signal, newVolume:0, -758.000000 08-23 11:18:52.481 audioserver(362) 4716 E APM_AudioPolicyManager: setStreamVolumeIndex: Btv not play !!! set_btv_media_volume: 08-23 11:18:52.482 com.skb.btvservice@1.0-service(377) 2046 W btvSocHalService-JNI: btvSocHalService_SetMediaAudioVolume::526 : in 08-23 11:18:52.482 com.skb.btvservice@1.0-service(377) 2047 I TVService-23.01.26: [TVService::setMicVolume]: volume(0) 08-23 11:18:52.482 com.skb.btvservice@1.0-service(377) 2047 I FrontPanelManager: [setMicVolume] called volume(0) 08-23 11:18:52.482 com.skb.btvservice@1.0-service(377) 2047 W btvSocHalService-JNI: btvSocHalService_SetMicVolume::1037 : in 08-23 11:18:52.482 com.skb.btvsochalservice@1.0-service(379) - D DEVICE : BTF\|DEVICE_SetMicVolume\|339\|IN\| device:ea410278, volume_level:0 08-23 11:18:52.482 com.skb.btvsochalservice@1.0-service(379) - D DEVICE : ++DEVICE_SetMicVolume volume_level(0) 08-23 11:18:52.482 com.skb.btvsochalservice@1.0-service(379) - I hal_mic : HAL_MicSetVolume v103(volume=0) 08-23 11:18:52.482 com.skb.btvsochalservice@1.0-service(379) 432 D BTV_HAL_MGR.606: [useSpeakerVolumePath] useSpeakerVolumePath : true 08-23 11:18:52.482 com.skb.btvsochalservice@1.0-service(379) 432 D halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1058\|IN\| player=e99a6000, volume=0 gain=-758.000000 08-23 11:18:52.483 com.skb.btvsochalservice@1.0-service(379) 432 E halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1067\|IN\| load volume, checkVolume : 0, checkGain : -758.000000 08-23 11:18:52.485 com.skb.btvsochalservice@1.0-service(379) 432 D halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1117\|OUT\| 08-23 11:18:52.485 com.skb.btvsochalservice@1.0-service(379) 432 D halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1058\|IN\| player=0, volume=0 gain=-758.000000 08-23 11:18:52.485 com.skb.btvsochalservice@1.0-service(379) 432 E halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1067\|IN\| load volume, checkVolume : 0, checkGain : -758.000000 08-23 11:18:52.485 com.skb.btvsochalservice@1.0-service(379) 432 E halMediaPlayer: BTF\|AVP_SetMediaAudioVolume\|1085\|IN\| player=0, return !!! 08-23 11:18:52.485 com.skb.btvservice@1.0-service(377) 2046 W btvSocHalService-JNI: btvSocHalService_SetMediaAudioVolume::529 : out, opStatus:0 08-23 11:18:52.485 audioserver(362) 2045 D BtvMediaVolumeControlLib: status : 0 08-23 11:18:52.487 com.skb.btvsochalservice@1.0-service(379) - D hal_mic : set /sys/devices/platform/soc/soc:apb@e80000/f7e81400.i2c/i2c-0/0-0018/adc3101_vol_ctl=0 08-23 11:18:52.487 com.skb.btvsochalservice@1.0-service(379) - I hal_mic : HAL_MicSetVolume set vol immediately 08-23 11:18:52.488 com.skb.btvsochalservice@1.0-service(379) - D hal_mic : set /sys/devices/platform/soc/soc:apb@e80000/f7e81400.i2c/i2c-0/0-0018/adc3101_mute_ctl=1 08-23 11:18:52.488 com.skb.btvsochalservice@1.0-service(379) - I hal_mic : HAL_MicSetVolume, 880,for Karaoke MIC, lv 0, priv_gs_mic_volume 0 08-23 11:18:52.488 com.skb.btvsochalservice@1.0-service(379) - D DEVICE : BTF\|DEVICE_SetMicVolume\|366\|OUT\| 08-23 11:18:52.488 com.skb.btvservice@1.0-service(377) 2047 W btvSocHalService-JNI: btvSocHalService_SetMicVolume::1040 : out, opStatus:0 08-23 11:18:52.488 audioserver(362) 4716 D BtvMediaVolumeControlLib: status : 1 08-23 11:18:52.489 android.hardware.audio@2.0-service(341) 436 D audio_hw: adev_set_parameters() line 796: adev=0xed74a000 kvpairs=btv_volume_index=0 08-23 11:18:52.489 android.hardware.audio@2.0-service(341) 436 I audio_hw: JIMDBG adev_set_parameters:797, adev=0xed74a000 kvpairs=btv_volume_index=0 08-23 11:18:52.494 audioserver(362) 4716 V APM: :AudioPolicyEngine: getDeviceForStrategy() strategy 0, device 80400 08-23 11:18:52.495 system_server(681) 1645 D AS.AudioService: setBtvUnmute end mIsBtvMute = false 08-23 11:18:52.501 android.hardware.audio@2.0-service(341) 18234 D MPP-PREPROCESS: [Tyche_MC_PreProc_Run] [CHANGE] SPK_MODE=1, SPK_VOL=0 08-23 11:18:52.798 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:52.799 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:53.015 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 55084 08-23 11:18:53.798 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:53.799 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:54.012 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 44180 08-23 11:18:54.802 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:54.802 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:55.006 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 49444 08-23 11:18:55.289 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: dismissH() mActiveStream :3 08-23 11:18:55.289 com.android.systemui(1731) - D vol.SPTEKVolumeDialogIm: dismissH r=timeout ss.muted=false 08-23 11:18:55.290 com.android.systemui(1731) - I vol.Events: writeEvent dismiss_dialog timeout 08-23 11:18:55.802 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:55.803 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:55.981 com.google.android.apps.mediashell(4508) 8581 I chromium: [4508:8581:INFO:grpc_manager_impl.cc(852)] GRPC[uri=[::]:8012]: num_pending_calls=0, num_active_handlers=0, num_free_handlers=0 08-23 11:18:56.019 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 58280 08-23 11:18:56.755 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=83, crc=37 71 1a db @@INFO:mcas-port-impl.c(739) 08-23 11:18:56.755 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:56.755 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=83, crc=37 71 1a db @@INFO:mcas-port-impl.c(739) 08-23 11:18:56.755 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:56.757 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=97, crc=26 c1 2f ab @@INFO:mcas-port-impl.c(739) 08-23 11:18:56.757 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:56.757 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=97, crc=26 c1 2f ab @@INFO:mcas-port-impl.c(739) 08-23 11:18:56.757 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:18:56.786 com.skb.stbPush(4455) - D PASMainServiceReceiver: onReceive() - action : com.skb.stbPush.pasrestart 08-23 11:18:56.787 com.skb.stbPush(4455) - W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.startService:1570 android.content.ContextWrapper.startService:669 android.content.ContextWrapper.startService:669 com.skb.stbPush.PASMainServiceReceiver.onReceive:27 android.app.ActivityThread.handleReceiver:3788 08-23 11:18:56.792 com.skb.stbPush(4455) - D PASMainService: [PAS]::onStartCommand() 08-23 11:18:56.801 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:56.802 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:56.860 com.google.android.gms.persistent(2114) 18999 I NetworkScheduler.Stats: Task com.google.android.gms/com.google.android.gms.auth.account.be.legacy.AuthCronService started execution. cause:4 exec_start_elapsed_seconds: 4227 [CONTEXT service_id=218 ] 08-23 11:18:57.013 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 62604 08-23 11:18:57.167 com.google.android.gms.persistent(2114) 2557 W GmsUrlRequestImpl: Content-Type header is overwritten. 08-23 11:18:57.224 com.skb.screensaver(8717) - D STBAPIManager: getScreenSaverTime() 3 08-23 11:18:57.224 com.skb.screensaver(8717) - D STBAPIManager: getLastUIShowTime() 08-23 11:18:57.225 com.skb.screensaver(8717) - D STBAPIManager: getLastInputKeyTime() 2023-08-23 11:18 08-23 11:18:57.225 com.skb.screensaver(8717) - D STBAPIManager: getDebug() 08-23 11:18:57.225 com.skb.screensaver(8717) - I SystemPropertiesUtil: sys.stb.on : 1 08-23 11:18:57.227 com.skb.screensaver(8717) - I Appstats: getTopPackage() com.skb.tv 08-23 11:18:57.228 com.skb.screensaver(8717) - I ScreenSaverService: handleMessage() 08-23 11:18:57.228 com.skb.screensaver(8717) - I ScreenSaverService: now time: 2023. 8. 23. 오전 11:18:57 08-23 11:18:57.228 com.skb.screensaver(8717) - I ScreenSaverService: ui time: 2023. 8. 23. 오전 11:18:57 08-23 11:18:57.228 com.skb.screensaver(8717) - I ScreenSaverService: key time: 2023. 8. 23. 오전 11:18:00 08-23 11:18:57.228 com.skb.screensaver(8717) - I ScreenSaverService: set time: 3 hours 08-23 11:18:57.228 com.skb.screensaver(8717) - I ScreenSaverService: is Show ScreenSaver? false 08-23 11:18:57.228 com.skb.screensaver(8717) - I ScreenSaverService: is STB sleep? false 08-23 11:18:57.228 com.skb.screensaver(8717) - I ScreenSaverService: is debug? false 08-23 11:18:57.228 com.skb.screensaver(8717) - I ScreenSaverService: is Home pause? false 08-23 11:18:57.228 com.skb.screensaver(8717) - I ScreenSaverService: is mIsShowNugu? false 08-23 11:18:57.281 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:18:57.346 com.google.android.gms.persistent(2114) 18999 I NetworkScheduler.Stats: Task com.google.android.gms/com.google.android.gms.auth.account.be.legacy.AuthCronService finished executing. cause:4 result: 1 elapsed_millis: 516 uptime_millis: 516 exec_start_elapsed_seconds: 4227 [CONTEXT service_id=218 ] 08-23 11:18:57.805 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:57.807 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:58.004 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 41172 08-23 11:18:58.807 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:58.808 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:18:59.018 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 50008 08-23 11:18:59.425 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:18:59.426 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:18:59.431 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:18:59.431 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:18:59.445 com.skb.tv(2755) - I TvNotificationListenerService: onNotificationPosted() 08-23 11:18:59.464 com.skb.tv(2755) 13254 I TvNotificationManager: getNotificationCount() count : 1 08-23 11:18:59.808 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:18:59.808 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:00.005 com.vasott.ampotaclient(5365) - I AmpService: currentHour=11 08-23 11:19:00.005 com.vasott.ampotaclient(5365) - I AmpService: currentDate=20230823 08-23 11:19:00.005 com.vasott.ampotaclient(5365) - I AmpService: UpdateDate= 08-23 11:19:00.005 com.vasott.ampotaclient(5365) - I commFunc: ===>get_property: sys.stb.on 08-23 11:19:00.005 com.vasott.ampotaclient(5365) - I commFunc: [/system/bin/sh, -c, getprop sys.stb.on] 08-23 11:19:00.015 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 45308 08-23 11:19:00.067 com.vasott.ampotaclient(5365) - I commFunc: CMD REP:1 08-23 11:19:00.113 com.vasott.ampotaclient(5365) - I commFunc: getOut=1, length=1 08-23 11:19:00.113 com.vasott.ampotaclient(5365) - I AmpService: screenOn=true 08-23 11:19:00.808 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:00.810 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:01.004 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 43992 08-23 11:19:01.809 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:01.809 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:02.003 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 57152 08-23 11:19:02.188 com.skt.aidev.nugustb(18133) 18236 I libTycheWakeup: Wakeup health check (2): feat.cnt=46000, pwr=6561.000000, score=-0.992987 08-23 11:19:02.282 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 14040 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][sc_msg_ecm_ts_read] Message CP Num [0x90f][0x910] @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 14040 D BOXKEY-MEDIA: DBGMSG level(1) [MCAS][sc_msg_ecm_ts_read] Start ECM processing, pid[0x170f] @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(1) [cas_sc] DHMessageRecv ret = 0xec003780 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) msg = 12 ..in cas_sc() @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) msg->length(184), msg->msg(0xec053780) @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) msg (0xec003780) @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) [task_sc_ecm_read] channel number = 5, view id = 0 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(1) [MCAS][task_sc_ecm_read] ECM Time :[ 2023.08.23 11:19:01 ] @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) ECM message Dump(length=175) @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) 00 00 00 05 81 70 ac 0a 04 01 00 01 00 08 0f 00 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) e8 90 69 a5 00 b4 82 09 10 09 11 00 01 18 c6 f9 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) 24 f0 51 89 a4 41 76 28 9a 21 87 cf 18 2a 9d 11 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) ed b8 5c 85 39 9d c4 b6 ee a8 b0 51 98 08 07 be @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) 35 a7 c1 4a 22 46 e0 de 0b 65 27 ee 45 75 0f e1 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) 01 0d d6 ce 4a 65 a2 3c 84 a5 04 09 8a 94 b2 50 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) 5d c5 0f 9e 2e cc 5f ae f4 ee b6 05 d4 94 1a e1 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) b1 00 ad 72 1a 71 b8 cb 36 79 9e 2d 8a ab 85 05 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) bc 3d 78 a9 de 7e 97 82 26 3f 61 5c 40 99 2b b0 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) 4a cc 7a 95 b1 56 63 ca 0a 2b ee 44 20 20 fe 97 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) 15 08 af 03 6e 0d 25 51 f7 09 61 6a 3a 89 01 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.689 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) sending ecm data length =175 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) [SC_DRV_DataSend] End Success @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) SC_DRV_DataSend ret_code = 1 sw1=0x61, sw2=0x27 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) [ECM_DUMP]: sca_ecm_data smartcard receive SW = 90 00 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) [ECM_DUMP] result = 0x5, callback count = 0x0 record = 0x0, length=0 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) [task_sc_ecm_read]08-3 : ERROR ret_code = 0 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][task_sc_ecm_read] sc task Semaphore Wait @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][task_sc_ecm_read] sc task Semaphore Signal @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: [task_sc_ecm_read]08 : Control Word is returned..view_id =0 service_id =5. ippv_result=5 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) CW[0] : d1 ac 24 5b ef c8 3a cf : CP = 0910 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) CW[1] : 10 aa 4c 94 d6 3c 24 4b : CP = 0911 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(1) [MCAS][channel_info_set_error_status] ECM processing completed, pid[0x170f], status code[254] @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][task_msg_send] Task[-328248784], Type[34] @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 657 D BOXKEY-MEDIA: DBGMSG level(3) [cas_sc] cas_sc message loop end @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 656 D BOXKEY-MEDIA: DBGMSG level(1) [cas_main]DHMessageRecv ret = 0xec003400 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 656 D BOXKEY-MEDIA: DBGMSG level(3) msg = 34 ..in cas_main() @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 656 D BOXKEY-MEDIA: DBGMSG level(3) msg->length(4), msg->msg(0xefd54210) @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 656 D BOXKEY-MEDIA: DBGMSG level(3) msg (0xec003400) @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.690 com.skb.btvservice@1.0-service(377) 656 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS_MSG_SET_STATUS] view_id = 0, pid = 5903 @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.691 com.skb.btvservice@1.0-service(377) 656 D BOXKEY-MEDIA: DBGMSG level(1) [MCAS][MCAS_SetError] ServiceID[5], ViewID[0], Status[254] @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.691 com.skb.btvservice@1.0-service(377) 656 D BOXKEY-MEDIA: [caNotify] called[vendor/skb/framework/btf_dev/interface/btvservice/1.0/default/btvmediaplayer/libs/libiptvmediahal/IptvSocPlayer.cpp], opcode : 0, msg : 115, pvParams:0xefd541f0, ulParamLength:8 @@INFO:IptvSocPlayer.cpp(1372) 08-23 11:19:02.691 com.skb.btvservice@1.0-service(377) 656 D BOXKEY-MEDIA: [casinfo] CAS_STATUS_CODE : ERR_VIEW_BY_ENTL (254)(254), g_pMainIptvPlayer:0xefd9ab00, pService_status_st->service_id : 5 @@INFO:IptvSocPlayer.cpp(1414) 08-23 11:19:02.691 com.skb.btvservice@1.0-service(377) 656 D BOXKEY-MEDIA: DBGMSG level(3) [cas_main] cas_main message loop end @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.691 com.skb.btvservice@1.0-service(377) 14040 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][task_msg_send] Task[-329301456], Type[12] @@INFO:mcas-port-impl.c(739) 08-23 11:19:02.810 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:02.810 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:03.002 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 47564 08-23 11:19:03.810 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:03.810 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:04.006 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 45872 08-23 11:19:04.812 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:04.812 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:05.002 com.vasott.ampotaclient(5365) - I AmpService: currentHour=11 08-23 11:19:05.003 com.vasott.ampotaclient(5365) - I AmpService: currentDate=20230823 08-23 11:19:05.003 com.vasott.ampotaclient(5365) - I AmpService: UpdateDate= 08-23 11:19:05.003 com.vasott.ampotaclient(5365) - I commFunc: ===>get_property: sys.stb.on 08-23 11:19:05.003 com.vasott.ampotaclient(5365) - I commFunc: [/system/bin/sh, -c, getprop sys.stb.on] 08-23 11:19:05.002 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ AVP_InjectTS feed delay gap(52) > 10ms, written : 24064, total_lengthData: 47188 08-23 11:19:05.004 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ feed delay gap(54062) > BUFFERING_DURATION(20000) 08-23 11:19:05.004 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 31020 08-23 11:19:05.009 com.google.android.apps.mediashell(4508) 8635 I chromium: [4508:8635:INFO:ssdp_device.c(101)] SSDP packets sent for 83 seconds = 4 08-23 11:19:05.049 com.vasott.ampotaclient(5365) - I commFunc: CMD REP:1 08-23 11:19:05.050 com.vasott.ampotaclient(5365) - I commFunc: getOut=1, length=1 08-23 11:19:05.050 com.vasott.ampotaclient(5365) - I AmpService: screenOn=true 08-23 11:19:05.815 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:05.817 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:06.002 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 48128 08-23 11:19:06.297 com.google.android.gms(14566) 17988 E memtrack: Couldn't load memtrack module 08-23 11:19:06.297 com.google.android.gms(14566) 17988 W android.os.Debug: failed to get memory consumption info: -1 08-23 11:19:06.816 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:06.816 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:07.001 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 37224 08-23 11:19:07.281 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:19:07.817 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:07.818 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:08.006 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 40232 08-23 11:19:08.818 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:08.818 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:09.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ AVP_InjectTS feed delay gap(49) > 10ms, written : 24064, total_lengthData: 29328 08-23 11:19:09.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ feed delay gap(49431) > BUFFERING_DURATION(20000) 08-23 11:19:09.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 11844 08-23 11:19:09.438 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:19:09.440 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:19:09.445 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:19:09.447 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:19:09.456 com.skb.tv(2755) - I TvNotificationListenerService: onNotificationPosted() 08-23 11:19:09.486 com.skb.tv(2755) 13254 I TvNotificationManager: getNotificationCount() count : 1 08-23 11:19:09.524 com.skb.btv.bluetooth(4828) - D BtvBluetoothUtils: isBTControl name = BRM_BA02_458 08-23 11:19:09.524 com.skb.btv.bluetooth(4828) - D BtvBluetoothUtils: isBTKids name = BRM_BA02_458 08-23 11:19:09.529 com.skb.btv.bluetooth(4828) - I forLogSystem: startGetConnectedDevice main RCU BONDED name(BRM_BA02_458) Connected(true) address(14:4E:34:F0:14:58) 08-23 11:19:09.611 com.skb.btv.bluetooth(4828) - D BtvBtLogService: BTLogSvc thread1 mIsStartThreadtrue 08-23 11:19:09.614 com.skb.btv.bluetooth(4828) 19357 D BtvBtLogService: BTLogSvc file exists filelines = 186 08-23 11:19:09.617 com.skb.btv.bluetooth(4828) 19357 D BtvBtLogService: BTLogSvc parsinglastline result=2023-08-2311:11:11.974timedata = 2023-08-23 11:11:11.974 08-23 11:19:09.619 com.skb.btv.bluetooth(4828) 19357 D BtvBtLogService: BTLogSvc BTLogSvc BufferedReader start time=2023-08-2311:11:11.974 08-23 11:19:09.671 com.skb.btv.bluetooth(4828) 4845 W System : A resource failed to call close. 08-23 11:19:09.671 com.skb.btv.bluetooth(4828) 4845 W System : A resource failed to call close. 08-23 11:19:09.817 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:09.818 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:10.013 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 43428 08-23 11:19:10.817 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:10.817 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:11.017 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 44368 08-23 11:19:11.819 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:11.819 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:12.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ AVP_InjectTS feed delay gap(49) > 10ms, written : 24064, total_lengthData: 31208 08-23 11:19:12.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ feed delay gap(49911) > BUFFERING_DURATION(20000) 08-23 11:19:12.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 8460 08-23 11:19:12.281 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:19:12.284 com.skt.aidev.nugustb(18133) 18236 I libTycheWakeup: Wakeup health check (2): feat.cnt=47000, pwr=15376.000000, score=-0.992099 08-23 11:19:12.458 com.skb.btv.bluetooth(4828) 19357 D BtvBtLogService: BTLogSvc Thread exit 08-23 11:19:12.820 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:12.821 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:12.835 com.skb.btv.logsystem(2849) 5028 D BtvLogsystemApk: AgentService: remoteControlBLuetoothSetup: Thread: btsvc_data isRemoconParing(true) isConnected(true) rcuBatteryLevel(83)) address(14:4E:34:F0:14:58) 08-23 11:19:12.835 com.skb.btv.logsystem(2849) 5028 I BtvLogsystemApk: AgentService: IUIAppToAgentService: send_data: data=;83;1;1;14:4E:34:F0:14:58 08-23 11:19:12.835 com.skb.btv.logsystem(2849) 5028 D iSQMSLib_LogSystem: ###### CISQMS::set_data nCategoryID=1, SubCategoryID=5, nFieldID=-1, dataLen = 25, pData=;83;1;1;14:4E:34:F0:14:58 08-23 11:19:12.835 com.skb.btv.logsystem(2849) 5028 D iSQMSLib_LogSystem: INFO:push_data nType=2 08-23 11:19:12.835 com.skb.btv.logsystem(2849) 5028 D iSQMSLib_LogSystem: @@@@@@@@@@@@@@@@@@@@@@@ push m_nEventCnt = 1 08-23 11:19:13.002 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ AVP_InjectTS feed delay gap(51) > 10ms, written : 24064, total_lengthData: 49632 08-23 11:19:13.002 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ feed delay gap(51936) > BUFFERING_DURATION(20000) 08-23 11:19:13.002 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 26884 08-23 11:19:13.540 com.skb.btv.logsystem(2849) 5031 D iSQMSLib_LogSystem: CISQMS::make_msg SEND_DATA version=LOGSYSTEM_libiSQMSAgent_v2.1.1 08-23 11:19:13.540 com.skb.btv.logsystem(2849) 5031 D iSQMSLib_LogSystem: send_data_to_agent(/data/btv_home/run/isqms_unix_socket_agent) send data=$send_data$1$5$-1$;83;1;1;14:4E:34:F0:14:58 08-23 11:19:13.540 com.skb.btv.logsystem(2849) 5031 D iSQMSLib_LogSystem: send_data_to_agent sock write, close 08-23 11:19:13.540 com.skb.btv.logsystem(2849) 5031 D iSQMSLib_LogSystem: ######################## pop_data nType=2, m_nEventCnt=1, isqms->m_pISQMDATA=0 08-23 11:19:13.540 com.skb.btv.logsystem(2849) 5031 D iSQMSLib_LogSystem: @@@@@@@@@@@@@@@@@@@@@@@ send delete m_nEventCnt = 1 08-23 11:19:13.540 com.skb.btv.logsystem(2849) 5031 D iSQMSLib_LogSystem: @@@@@@@@@@@@@@@@@@@@@@@1 m_nEventCnt=0, isqms->m_pISQMDATA=0 08-23 11:19:13.540 skb_logsystem(2232) 2300 D iSQMSAgent_LogSystem: read data buffer=>$send_data$1$5$-1$;83;1;1;14:4E:34:F0:14:58@@ 08-23 11:19:13.540 skb_logsystem(2232) 2300 D iSQMSAgent_LogSystem: read data pData=>$send_data$1$5$-1$;83;1;1;14:4E:34:F0:14:58 08-23 11:19:13.540 skb_logsystem(2232) 2300 D iSQMSAgent_LogSystem: CISQMSMain::: ipc_data pData=$send_data$1$5$-1$;83;1;1;14:4E:34:F0:14:58 08-23 11:19:13.540 skb_logsystem(2232) 2300 D iSQMSAgent_LogSystem: CISQMSMain::nCategoryID=1, nSubCategoryID=5, nFieldID=-1, pszPtr=;83;1;1;14:4E:34:F0:14:58, len = 25 08-23 11:19:13.540 skb_logsystem(2232) 2300 D iSQMSAgent_LogSystem: CISQMS:set_data nCategoryID=1, SubCategoryID=5, nFieldID=-1, pData=;83;1;1;14:4E:34:F0:14:58 08-23 11:19:13.540 skb_logsystem(2232) 2300 D iSQMSAgent_LogSystem: CISQMSData::set_sub_category_data nCategoryID=1, nSubCategoryID=5, nFieldID=-1, pData=;83;1;1;14:4E:34:F0:14:58 08-23 11:19:13.540 skb_logsystem(2232) 2300 D iSQMSAgent_LogSystem: CISQMSData::set_sub_category_data##### get_property_value dev.rcu.battery=Normal, manu= , ver= , pData=;83;1;1;14:4E:34:F0:14:58; ; ;Normal 08-23 11:19:13.540 skb_logsystem(2232) 2300 D iSQMSAgent_LogSystem: _thread_read_data_from_agent_fifo running, recvthread 08-23 11:19:13.540 skb_logsystem(2232) 2300 D iSQMSAgent_LogSystem: _thread_read_data_from_agent_fifo read waiting 08-23 11:19:13.553 com.skb.stbPush(4455) 13227 V ClientState: MqttWireMessage loop (result is null22.) 08-23 11:19:13.553 com.skb.stbPush(4455) 13227 V ClientState: MqttWireMessage get2-2 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 D ClientState: [ClientState] ouboundMiliTime: 301000 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 D ClientState: [ClientState] inBoundMiliTime: 300995 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 D ClientState: [ClientState] keepAlive: 300000 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 D ClientState: [ClientState] checkForActivity pingOutstanding false. 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 V ClientState: MqttWireMessage get3 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 V ClientState: CommsSender MqttDeliveryToken flush. 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 D ClientState: [notifySent] lastOutboundActivity: 1692757153554 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 I MQTT : notifySent1 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 I MQTT : notifySent2 responseLock 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 I MQTT : notifySent3 sentLock 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 V ClientState: CommsSender run. 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 V ClientState: MqttWireMessage get call. 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 V ClientState: MqttWireMessage loop (result is null11.) 08-23 11:19:13.554 com.skb.stbPush(4455) 13227 V ClientState: MqttWireMessage get1 08-23 11:19:13.557 com.skb.stbPush(4455) 13226 I MQTT : MqttPingResp create!!!! 08-23 11:19:13.557 com.skb.stbPush(4455) 13226 D ClientState: [notifyReceived] lastInboundActivity: 1692757153557 08-23 11:19:13.557 com.skb.stbPush(4455) 13226 D UPNSCallback: [UPNSCallback] pingResponse! 08-23 11:19:13.561 com.skb.stbPush(4455) 13226 I MQTT : notifyReceived1 08-23 11:19:13.562 com.skb.stbPush(4455) 13226 I MQTT : notifyReceived2 responseLock 08-23 11:19:13.822 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:13.823 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:14.003 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 41360 08-23 11:19:14.821 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:14.821 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:15.016 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 58092 08-23 11:19:15.823 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:15.824 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:16.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ AVP_InjectTS feed delay gap(49) > 10ms, written : 24064, total_lengthData: 59784 08-23 11:19:16.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ feed delay gap(49659) > BUFFERING_DURATION(20000) 08-23 11:19:16.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 35720 08-23 11:19:16.824 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:16.824 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:17.002 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 51512 08-23 11:19:17.281 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:19:17.824 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:17.824 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:18.001 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 45872 08-23 11:19:18.829 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:18.830 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:19.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 57716 08-23 11:19:19.451 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:19:19.456 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:19:19.462 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:19:19.462 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:19:19.472 com.skb.tv(2755) - I TvNotificationListenerService: onNotificationPosted() 08-23 11:19:19.499 com.skb.tv(2755) 13254 I TvNotificationManager: getNotificationCount() count : 1 08-23 11:19:19.829 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:19.829 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:20.017 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 53016 08-23 11:19:20.829 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:20.830 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:21.011 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 35532 08-23 11:19:21.306 com.google.android.apps.mediashell(4508) 8605 I chromium: [4508:8605:INFO:mdns_app_filter.cc(3154)] MdnsAppFilter: responses sent in 96 seconds: 2 08-23 11:19:21.829 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:21.829 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:22.012 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 43052 08-23 11:19:22.210 com.skt.aidev.nugustb(18133) 18236 I libTycheWakeup: Wakeup health check (2): feat.cnt=48000, pwr=12769.000000, score=-0.992543 08-23 11:19:22.282 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:19:22.750 com.skb.btv.logsystem(2849) 5020 D BtvLogsystemApk: TimerClass: check push agent connection. 08-23 11:19:22.750 com.skb.btv.logsystem(2849) 5020 I BtvLogsystemApk: PushServiceClient: isRegisteredService 08-23 11:19:22.833 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:22.833 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:23.015 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 37412 08-23 11:19:23.492 com.android.bluetooth(1709) 2150 I bt_btm_sec: btm_sec_disconnected clearing pending flag handle:1 reason:8 08-23 11:19:23.752 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=83, crc=55 ff 3c a3 @@INFO:mcas-port-impl.c(739) 08-23 11:19:23.752 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:23.752 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=83, crc=55 ff 3c a3 @@INFO:mcas-port-impl.c(739) 08-23 11:19:23.752 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:23.834 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:23.834 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:24.016 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 44368 08-23 11:19:24.833 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:24.834 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:25.012 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 54896 08-23 11:19:25.828 skb_logsystem(2232) - I QSMServer_LogSystem: QSM check system info : mem_usage=68(limit:95)cnt:0, cpu_usage=42(limit:90)cnt:0, disk_usage=24(limit:90), qsm_addition_debug_log_on(0) QSM_LogSystem_version : 3.7.10 08-23 11:19:25.833 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:25.834 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:26.001 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 48316 08-23 11:19:26.834 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:26.834 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:27.005 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 50572 08-23 11:19:27.282 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:19:27.444 com.google.android.gms.persistent(2114) 17144 I NetworkScheduler.Stats: Task com.google.android.gms/com.google.android.gms.auth.account.be.legacy.AuthCronService started execution. cause:4 exec_start_elapsed_seconds: 4257 [CONTEXT service_id=218 ] 08-23 11:19:27.467 com.google.android.gms.persistent(2114) 17144 I NetworkScheduler.Stats: Task com.google.android.gms/com.google.android.gms.auth.account.be.legacy.AuthCronService finished executing. cause:4 result: 1 elapsed_millis: 58 uptime_millis: 58 exec_start_elapsed_seconds: 4257 [CONTEXT service_id=218 ] 08-23 11:19:27.834 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:27.834 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:28.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ AVP_InjectTS feed delay gap(49) > 10ms, written : 24064, total_lengthData: 57152 08-23 11:19:28.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ feed delay gap(49508) > BUFFERING_DURATION(20000) 08-23 11:19:28.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 33088 08-23 11:19:28.835 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:28.836 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:29.014 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 49820 08-23 11:19:29.466 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:19:29.470 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:19:29.474 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:19:29.475 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:19:29.484 com.skb.tv(2755) - I TvNotificationListenerService: onNotificationPosted() 08-23 11:19:29.506 com.skb.tv(2755) 13254 I TvNotificationManager: getNotificationCount() count : 1 08-23 11:19:29.836 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:29.837 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:30.000 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 40608 08-23 11:19:30.837 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:30.837 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:31.001 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ AVP_InjectTS feed delay gap(50) > 10ms, written : 24064, total_lengthData: 55836 08-23 11:19:31.001 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ feed delay gap(50969) > BUFFERING_DURATION(20000) 08-23 11:19:31.001 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 38352 08-23 11:19:31.839 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:31.840 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:32.003 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 56776 08-23 11:19:32.140 com.skt.aidev.nugustb(18133) 18236 I libTycheWakeup: Wakeup health check (2): feat.cnt=49000, pwr=33489.000000, score=-0.993164 08-23 11:19:32.283 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:19:32.760 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=78, crc=df ad f9 cc @@INFO:mcas-port-impl.c(739) 08-23 11:19:32.760 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:32.760 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=78, crc=df ad f9 cc @@INFO:mcas-port-impl.c(739) 08-23 11:19:32.760 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:32.840 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:32.841 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:32.873 com.skb.btvservice@1.0-service(377) 14037 I [PIX_ADS]: [AddrAD][ThreadADParserProc][1256]: BYPASS: 2800000 08-23 11:19:33.004 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 48504 08-23 11:19:33.216 com.skb.btvservice@1.0-service(377) 14036 D PIX_MLR : [INFO] 2. Change MLR Delay Time 5~5ms -> 150~300ms.Wifi-Audio OFF. 08-23 11:19:33.840 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:33.841 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:34.016 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 54520 08-23 11:19:34.840 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:34.840 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:35.008 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 37224 08-23 11:19:35.446 com.skb.tv(2755) - I chromium: [INFO:CONSOLE(2)] "###################################################", source: https://agw.sk-iptv.com:8443/ui5web/v540/nuxt/js/app.js?1187d83b57b70e63f4a5 (2) 08-23 11:19:35.447 com.skb.tv(2755) - I chromium: [INFO:CONSOLE(2)] "[Memory] usedJSHeapSize : 18743176", source: https://agw.sk-iptv.com:8443/ui5web/v540/nuxt/js/app.js?1187d83b57b70e63f4a5 (2) 08-23 11:19:35.447 com.skb.tv(2755) - I chromium: [INFO:CONSOLE(2)] "[Memory] totalJSHeapSize : 27684948", source: https://agw.sk-iptv.com:8443/ui5web/v540/nuxt/js/app.js?1187d83b57b70e63f4a5 (2) 08-23 11:19:35.449 com.skb.tv(2755) - I chromium: [INFO:CONSOLE(2)] "[Memory] jsHeapSizeLimit : 553648128", source: https://agw.sk-iptv.com:8443/ui5web/v540/nuxt/js/app.js?1187d83b57b70e63f4a5 (2) 08-23 11:19:35.450 com.skb.tv(2755) - I chromium: [INFO:CONSOLE(2)] "###################################################", source: https://agw.sk-iptv.com:8443/ui5web/v540/nuxt/js/app.js?1187d83b57b70e63f4a5 (2) 08-23 11:19:35.841 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:35.842 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:36.006 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 50384 08-23 11:19:36.841 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:36.841 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:37.005 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 36848 08-23 11:19:37.283 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:19:37.525 skb_logsystem(2232) 2292 I QSMServer_LogSystem: QSM s_qsm_tx_cond_timed_wait : exit tx thread timed wait 08-23 11:19:37.526 skb_logsystem(2232) 2292 I QSMServer_LogSystem: QSM : qsm_log_switch_parse_others type : RTP(-1,2), : 300:16547:2686854049:5:2168 08-23 11:19:37.528 skb_logsystem(2232) 2292 I QSMServer_LogSystem: QSM s_qsm_tx_cond_timed_wait : enter tx thread timed wait 08-23 11:19:37.844 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:37.844 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:38.010 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 37788 08-23 11:19:38.845 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:38.845 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:39.009 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 46812 08-23 11:19:39.475 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:19:39.477 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:19:39.485 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:19:39.487 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:19:39.493 com.skb.tv(2755) - I TvNotificationListenerService: onNotificationPosted() 08-23 11:19:39.505 com.skb.tv(2755) 13254 I TvNotificationManager: getNotificationCount() count : 1 08-23 11:19:39.771 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=72, crc=1a d0 b9 67 @@INFO:mcas-port-impl.c(739) 08-23 11:19:39.771 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:39.771 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=72, crc=1a d0 b9 67 @@INFO:mcas-port-impl.c(739) 08-23 11:19:39.771 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:39.845 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:39.845 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:40.010 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 41172 08-23 11:19:40.847 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:40.850 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:41.016 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 64860 08-23 11:19:41.758 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=106, crc=f8 99 80 26 @@INFO:mcas-port-impl.c(739) 08-23 11:19:41.758 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:41.758 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=106, crc=f8 99 80 26 @@INFO:mcas-port-impl.c(739) 08-23 11:19:41.758 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:41.846 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:41.848 com.google.android.apps.mediashell(4508) 8635 I chromium: [4508:8635:INFO:ssdp_device.c(101)] SSDP packets sent for 37 seconds = 4 08-23 11:19:41.849 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:42.011 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 48692 08-23 11:19:42.245 com.skt.aidev.nugustb(18133) 18236 I libTycheWakeup: Wakeup health check (2): feat.cnt=50000, pwr=58081.000000, score=-0.987216 08-23 11:19:42.260 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: CISQMSHolePunching::send_keep_alive_to_report_server create_holepunching_msg 08-23 11:19:42.260 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: INFO: NET make_holepunching_msg 08-23 11:19:42.260 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: CISQMSData::INFO: NET make_holepunching_msg HTYPE_KEEP_ALIVE pszTemp2=;0;1;192.168.40.112;255.255.255.0;192.168.40.1;211.44.250.88;121.125.25.110 08-23 11:19:42.260 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: CISQMSData::INFO: NET make_holepunching_msg HTYPE_KEEP_ALIVE this->szSTBIP=192.168.40.112 08-23 11:19:42.260 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: CISQMSComm::send_data_to_hp_server m_HPServerInfo.nPort = 30812 pData = [SEQ=69 08-23 11:19:42.260 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: T=KEEP-ALIVE 08-23 11:19:42.260 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: STB_ID=E13FB3EC-EDA0-11EA-BD09-5F5CE34E524C 08-23 11:19:42.260 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: STB_MAC=001238000938 08-23 11:19:42.260 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: STB_IP=192.168.40.112 08-23 11:19:42.260 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: ] 08-23 11:19:42.260 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: send_data_to_hp_server already connected nSockID(16) - write return : 107 08-23 11:19:42.260 skb_logsystem(2232) 2295 D iSQMSAgent_LogSystem: CISQMSData::destory_holepunching_msg 08-23 11:19:42.283 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:19:42.847 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:42.848 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:43.015 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 52264 08-23 11:19:43.759 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=102, crc=b5 6a fd 2b @@INFO:mcas-port-impl.c(739) 08-23 11:19:43.759 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:43.759 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=102, crc=b5 6a fd 2b @@INFO:mcas-port-impl.c(739) 08-23 11:19:43.759 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:43.759 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=102, crc=b3 0d ad dc @@INFO:mcas-port-impl.c(739) 08-23 11:19:43.759 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:43.759 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=102, crc=b3 0d ad dc @@INFO:mcas-port-impl.c(739) 08-23 11:19:43.763 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:43.852 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:43.853 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:44.015 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 43992 08-23 11:19:44.853 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:44.853 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:45.019 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 67680 08-23 11:19:45.857 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:45.857 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:46.002 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 46624 08-23 11:19:46.857 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:46.858 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:47.002 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 44368 08-23 11:19:47.283 com.ti2.mdns(5282) - D MDNSService: MDNSMsgHandler() AM_EVENT_CHECK_NETWORK_CONFIG. isConnected : true 08-23 11:19:47.858 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:47.858 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:48.011 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 50008 08-23 11:19:48.773 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=72, crc=c3 59 6d a1 @@INFO:mcas-port-impl.c(739) 08-23 11:19:48.773 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:48.773 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][crc_check] CRC Error: length=72, crc=c3 59 6d a1 @@INFO:mcas-port-impl.c(739) 08-23 11:19:48.773 com.skb.btvservice@1.0-service(377) 659 D BOXKEY-MEDIA: DBGMSG level(3) [MCAS][multi_ca_section][ERROR] EMM CRC Check Error. PID[0x1388], EMM Len[372] @@INFO:mcas-port-impl.c(739) 08-23 11:19:48.859 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:48.861 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:49.001 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 48692 08-23 11:19:49.487 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:19:49.489 system_server(681) 1641 W TelecomManager: Telecom Service not found. 08-23 11:19:49.494 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:19:49.496 system_server(681) - W TelecomManager: Telecom Service not found. 08-23 11:19:49.504 com.skb.tv(2755) - I TvNotificationListenerService: onNotificationPosted() 08-23 11:19:49.522 com.skb.tv(2755) 13254 I TvNotificationManager: getNotificationCount() count : 1 08-23 11:19:49.860 com.skb.tv(2755) - D STBAPIManager: getProperty() key : PROPERTY_NUGU_MIC_TIME, result : night 08-23 11:19:49.862 com.skb.tv(2755) - D STBAPIManager: getNuguMicState() : true 08-23 11:19:49.923 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:service_base.h(78)] Size of the map for type AuthTokenResponse is 0 08-23 11:19:50.004 com.skb.btvservice@1.0-service(377) 14041 D BOXKEY-STDVB: [DEVICE 0] ++ remain buffer size : 39104 08-23 11:19:50.123 com.google.android.apps.mediashell(4508) - I chromium: [4508:4508:INFO:service_base.h(246)] Container sizes for type CloudSetting are (session_info_map: 0), (topic_info_map: 21), (pending_events: 0). 2023-08-23 11:19:50.370 LogRabbit(---) --- LR ---: Disconnected 192.168.40.112:5555 apple_tv_bt_connect_fail.log (158,801 bytes)

(ALTech) Wooshin Kang 2023-08-28 09:51 developer ~0013999	Hi Jason, Is there any update ? lt looks there is disconnect after SPD transfer. Thanks.

~~(SW) Jason Ling~~ 2023-08-28 09:59 developer ~0014000	Hi Wooshin, We hasn't started investigating this issue. I am working on other issue now. I have one more question, Did the paring issue mean: 1. AppleTV 4K can not discover the the swan or 2. AppleTV 4K can discover the swan but can not pair with the swan. Thanks, Jason

(ALTech) Wooshin Kang 2023-08-28 10:02 developer ~0014002	Hi Jason, It is No.2. thanks.

~~(SW) Jason Ling~~ 2023-08-30 11:04 developer ~0014020	Hi Sangmin, Wooshin, I only saw LMP Respoonse timeout in btsnnop (Please refer to LMP_Response_Timeout.jpg) I hasn't found new clues in your logcat. If you can reproduce it on your side, Please enable more debug level and capture logcat/snoop again, Use user-debug build and remount the Swan enable BT snoop function by UI 130\|BFX-UA300:/ # su 130\|BFX-UA300:/ # vi system/etc/bluetooth/bt_stack.conf (please refer to bt_stack.jpg) 130\|BFX-UA300:/ # setprop persist.bluetooth.btsnooplogmode full reboot 1. Please provide logcat (logcat -v time) and btsnoop 2. Please let me know "when" did you start pairing and "when" did you see the pairing fail? 3. Please let me know your device BT MAC and AppleTV BT MAC By the way, Can the apple tv be paired with other speakers you have? Thanks, Jason LMP_Response_Timeout.jpg (41,106 bytes) LMP_Response_Timeout.jpg (41,106 bytes) bt_stack.jpg (190,426 bytes) bt_stack.jpg (190,426 bytes)

(ALTech) Sangmin Choi 2023-08-30 12:17 developer ~0014021	Hello, Jason, I can see the followings in btsnoop_hci_connect_apple_tv.cfa, I don't know it's useful or not. Please check the attached image. Other bluetooth speaker(JBL Flip 4) could be connected with Apple TV 4K. If you need more snoop log, please let me know again. Thank you. Sangmin Choi. Apple_TV_wireshark.png (1,127,341 bytes)

(ALTech) Sangmin Choi 2023-08-30 12:17 developer ~0014022	Apple_TV_wireshark.jpg (1,691,274 bytes)

(ALTech) Wooshin Kang 2023-08-30 12:42 developer ~0014023 Last edited: 2023-08-30 13:12	Hi Jason, Please check below. - STB BT MAC : A8:93:4A:FC:F2:E1 - APPLE TV BT MAC : 54:e6:1b:f1:ce:ac start time : 13:36:55 fail time : 13:37:38 apple_tv.log (8,757,930 bytes)

(ALTech) Wooshin Kang 2023-08-30 12:46 developer ~0014024	snoop log is also attached. btsnoop_hci.cfa (46,424 bytes)

(ALTech) Wooshin Kang 2023-08-30 13:33 developer ~0014025	And You can find Mac address of devices using "string search" like attached picture 스크린샷 2023-08-30 오후 2.32.52.png (2,660,874 bytes)

(ALTech) Wooshin Kang 2023-08-31 12:46 developer ~0014047	Hi Jason, Any update ? Thanks.

~~(SW) Jason Ling~~ 2023-08-31 13:35 developer ~0014050	Hi Wooshin, I already forwarded the logcat/snoop to Realtek (BT Vendor), If I have any update I'll let you know. Thanks, Jason

(ALTech) Wooshin Kang 2023-09-04 14:48 developer ~0014070	Hi Jason, Do you have any update from BT vendor ? Thanks.

~~(SW) Jason Ling~~ 2023-09-04 15:00 developer ~0014071	Hi Wooshin, No, base on previous experience, They need a week to investigate it. If there is no response after a week, I will push them again. Thanks, Jason

(ALTech) Wooshin Kang 2023-09-12 09:27 developer ~0014132	Hi Jason, Please update current status. Thanks.

~~(SW) Jason Ling~~ 2023-09-12 09:47 developer ~0014133	Hi Wooshin, Yesterday Morning, I pushed Realtek, but he replied us "Is it a normal usage situation for OTT (AppleTV) to pair with OTT (Swan) ?". so I convinced him that this was a normal use case. Thanks, Jason

~~(SW) Jason Ling~~ 2023-09-12 20:10 developer ~0014135	Hi Wooshin, Sangmin, Realtek's reply is as follows, Please set the following property and test it again, setprop persist.vendor.bluetooth.prefferedrole slave if it still have problem, Please reproduce it with prefferedrole = false, and enable more debug level and capture logcat/snoop again, Use user-debug build and remount the Swan enable BT snoop function by UI 130\|BFX-UA300:/ # su 130\|BFX-UA300:/ # vi system/etc/bluetooth/bt_stack.conf (please refer to bt_stack.jpg) 130\|BFX-UA300:/ # setprop persist.bluetooth.btsnooplogmode full reboot 1. Please provide logcat (logcat -v time) and btsnoop 2. Please let me know "when" did you start pairing and "when" did you see the pairing fail? 3. Please let me know your device BT MAC and AppleTV BT MAC Thanks, Jason

(ALTech) Wooshin Kang 2023-09-13 09:40 developer ~0014136	Please check attached file. - Pairing start : 10:34:50 - Pairing stop : 10:35:30 - STB BT MAC : A8:93:4A:FC:F2:E1 - Apple TV MAC : 54:e6:1b:f1:ce:ac Thanks. btsnoop_hci-2.cfa (27,597 bytes) apple_tv_0913.log (6,712,202 bytes)

~~(SW) Jason Ling~~ 2023-09-13 11:29 developer ~0014137 Last edited: 2023-09-13 11:38	Hi Wooshin, I can not find the ApplTV's MAC in logcat, is it correct ? I saw the f0:b3:ec:7e:fc:f4 in the logcat and snoop. I also see you pair the RCU, could you simplify the test (using RCU IR mode, and only pair AppleTV) ? Please confirm it again, if you need to test it again, remember to set the prefferedrole property. Thanks, Jason

(ALTech) Wooshin Kang 2023-09-13 12:05 developer ~0014138 Last edited: 2023-09-13 12:05	I am sorry for confusing. f0:b3:ec:7e:fc:f4 is right.

(SW) Sam Yang 2023-09-15 16:14 developer ~0014148	Hi Wooshin, Realtek advises us to replace the BT FW. So I use the new BT FW and release a test FW. Please verify the issue with this FW: /release_by_fxn/swan_test_image/20230915_mantis1798/usb_bfx-ua300_V19.540.23_SD_r24hAppleTvPair.zip And if the issue resolved, please verify other BT related operations. Thanks~ Sam

(ALTech) Wooshin Kang 2023-09-15 16:39 developer ~0014149	Hi sam, I can't pair with Apple TV in test version. Please check again. Thanks. IMG_0804.JPG (1,460,682 bytes)

~~(SW) Jason Ling~~ 2023-09-15 16:58 developer ~0014150	Hi Wooshin, Did you setprop persist.vendor.bluetooth.prefferedrole slave? if still still fails Could you use test FW (including new BT FW) to capture logcat/snoop? We need to forward it to Realtek. Test 1: (new BT FW and didn't set prefferedrole) btsnoop/logcat -v time stb bt mac/apple tv btmac start time/end time Test 2: (new BT FW and set prefferedrole to false) btsnoop/logcat -v time stb bt mac/apple tv btmac start time/end time Thanks, Jason

(ALTech) Wooshin Kang 2023-09-15 17:30 developer ~0014151	Hi Jason, Did you setprop persist.vendor.bluetooth.prefferedrole slave? YES, Is it tested in your side or bt vendor side ? thanks

~~(SW) Jason Ling~~ 2023-09-15 17:47 developer ~0014152	Hi Wooshin, We don't have AppleTV, so we can not verify it. Therefore, All we can do is building a test FW for you to verify. Before release FW to you, we've verified the a2dp function (pair with phone), but we need your help to verify it with AppleTV Sorry for the inconvenience. Thanks, Jason

(ALTech) Wooshin Kang 2023-09-15 18:36 developer ~0014153	Hi Jason, time - slave : 19:21:52 ~ 19:22:25 - false : 19:31:33 ~ 19:33:10 Mac - STB BT MAC : A8:93:4A:FC:F2:E1 - Apple TV MAC : f0:b3:ec:7e:fc:f4

(ALTech) Wooshin Kang 2023-09-15 18:37 developer ~0014154	Please find attached file. apple_tv.zip (1,209,865 bytes)

(ALTech) Wooshin Kang 2023-09-18 09:40 developer ~0014157	Please find attached files. It is captured after "prefferedrole" set to default appletv_0918.log (27,006,665 bytes) btsnoop_hci-3.cfa (74,195 bytes)

~~(SW) Jason Ling~~ 2023-09-20 10:44 developer ~0014180 Last edited: 2023-09-20 10:44	Hi Wooshin, Please test it, Realtek updated the BT firmware /release_by_fxn/tmp/mantis1798/usb_bfx-ua300_V19.540.23_SD.zip BFX-UA300:/ $ su BFX-UA300:/ # getprop ro.vendor.vasott.version v540r24iAppleTvPair-20230919 Case 1: (Don't set anything) btsnoop/logcat -v time stb bt mac/apple tv btmac start time/end time adb pull logcat and snoop then setprop persist.vendor.bluetooth.prefferedrole slave then reboot to test Case2 Case 2: (set prefferedrole to slave) btsnoop/logcat -v time stb bt mac/apple tv btmac start time/end time Thanks, Jason

(ALTech) Wooshin Kang 2023-09-21 10:40 developer ~0014184 Last edited: 2023-09-21 10:41	Hi Jason, slave 11:30:05 ~ 11:30:38 none 11:36:25 ~ 11:38:03 Macs are same with previous. logs are attached. AppleTV_0921.zip (684,176 bytes)

(ALTech) Wooshin Kang 2023-09-25 16:46 developer ~0014217	Hi Jason, Do you have any update ? thanks.

~~(SW) Jason Ling~~ 2023-09-26 15:38 developer ~0014223	Hi Wooshin, I pushed Realtek today, He replied that AppleTV has their special connection parameter, Apple often has issues pairing with non-Apple devices Technically speaking, Apple's role switching mechanism is different from other device, so causing role switching to fail. (According to our test, only AppleTV 4k has pairing issue) Realtek said that there will be a BT FW release this week. if issue still happen, Realtek asked us to provide the air log (But we don't have air log capture device) to capture. They also said that even if we provide the air logs, Realtek can not guarantee to solve the problem unless we provide AppleTV technical window for them to discuss. Thanks, Jason

(ALTech) Wooshin Kang 2023-10-04 08:32 developer ~0014237	Hi Jason, Please inform me if there is any update. thanks.

~~(SW) Jason Ling~~ 2023-10-04 08:50 developer ~0014238	Hi Wooshin, I've pushed Realtek today. Because they didn't release new FW on time. Realtek said that their FW release team is in China, China is on national holiday. Realtek said that they plan to release new FW before this friday. But the next question is we don't have air sniffer. If the next BT FW still has issue. we can not capture air log for Realtek. Thanks, Jason

(ALTech) Wooshin Kang 2023-10-04 12:05 developer ~0014242	Hi Jason, SWAN can do role of STB and role for "Sound BAR". Sound BAR is similar device like BT speaker. So, SWAN should pair with APPLE TV because other BT speakers can pair with APPLE TV. If you have no air log capture device, you should find alternative. ex) rend your apple tv to realtek. Thanks Kang, Thanks,

~~(SW) Jason Ling~~ 2023-10-04 13:36 developer ~0014244	Hi Woooshin, The "role switch" doesn't mean Sink/Source switch, it's related to Master/Slave in HCI layer. Thanks, Jason

(ALTech) Wooshin Kang 2023-10-06 07:34 developer ~0014248	Hi Jason, Today is last day of this week. Is there any update ? Thanks.

~~(SW) Jason Ling~~ 2023-10-06 18:25 developer ~0014253	Hi Wooshin, We just received new BT firmware this afternoon. I've checked it with our AppleTV, but it still doesn't work. Now, Realtek asked us to provide the FW log and air log. Regarding the FW log, there is a FW log pin on BT chip, we need to work with Realtek and our HW engineer to check how to capture the FW log. Regarding the air log, if Realtek still can not find any clue in FW log, I'll ask them if they can check AppleTV directly (lend Swan and AppleTV to them) If we have any update, I'll let you know. Thanks, Jason

~~(SW) Jason Ling~~ 2023-10-13 16:49 developer ~0014304	Hi Wooshin, Just to let you know the status, I've worked with our HW engineer to capture the FW log and forwarded to Realtek, if there are any updates, I'll let you know. Thanks, Jason

(ALTech) Wooshin Kang 2023-10-16 09:16 developer ~0014308	Hi Jason, Thanks for information but time for solving issue is spending too much. Please push them. Thanks.

(ALTech) Wooshin Kang 2023-10-23 08:16 developer ~0014370	Hi Jason, Any update about this issue ? Thanks

~~(SW) Jason Ling~~ 2023-10-31 12:00 developer ~0014436	Hi Wooshin, According to BT snoop and FW log, Realtek RD said that AppleTV didn't send DHkey during simple paring. The DHKey CHECK flow will be executed when we declare the device has display in BT stack. (SWAN has display hdmi-out) This is why the current test results are as follows AI2/AISoudmax has hdmiout => can NOT pair with AppleTV Normal Bluetooth headphones or speakers => can pair with AppleTV After we change SWAN I/O capability to BTM_IO_CAP_NONE, the AppleTV can pair with SWAN I've discuss this modification with Realtek, Realtek said it's workaround, if AppleTV does not support sending DHKey. I think you can apply the following diff on your side and build ND image to test some operations. xts@xts-deskmini:~/Projects/swan/device/synaptics/sequoia$ git diff . diff --git a/bluetooth/bdroid_buildcfg.h b/bluetooth/bdroid_buildcfg.h index d843f5d..5de1f45 100755 --- a/bluetooth/bdroid_buildcfg.h +++ b/bluetooth/bdroid_buildcfg.h @@ -74,6 +74,7 @@ /page timeout / //Vas modified for UEI BLE RCU, River 2019/09/23 #define BTA_DM_PAGE_TIMEOUT 12288 //7.5 sec +#define BTM_LOCAL_IO_CAPS BTM_IO_CAP_NONE #define BTM_LOCAL_IO_CAPS_BLE BTM_IO_CAP_KBDISP I've verified the following items, 1. Pairing RCU 2. A2DP sink/source function 3. CTS-Verfier BT items 4. CTS CtsBluetoothTestCases Module 5. VTS VtsHalBluetoothV1_0Target Module If there is no failure, please let us know the jira id, I'll commit it. Thanks, Jason

Date Modified	Username	Field	Change
2023-08-22 12:49	(ALTech) Wooshin Kang	New Issue
2023-08-22 12:49	(ALTech) Wooshin Kang	Status	new => assigned
2023-08-22 12:49	(ALTech) Wooshin Kang	Assigned To	=> (SW) Jacky Chiang
2023-08-22 13:57	~~(SW) Jason Ling~~	Severity	s2-severe => s4-minor
2023-08-22 13:58	~~(SW) Jason Ling~~	Note Added: 0013940
2023-08-22 13:58	~~(SW) Jason Ling~~	Assigned To	(SW) Jacky Chiang => (ALTech) Wooshin Kang
2023-08-22 13:58	~~(SW) Jason Ling~~	Issue Monitored: (SW) Jason Ling
2023-08-23 08:43	(ALTech) Sangmin Choi	Note Added: 0013947
2023-08-23 08:43	(ALTech) Sangmin Choi	File Added: btsnoop_hci_connect_apple_tv.cfa
2023-08-23 09:57	~~(SW) Jason Ling~~	Note Added: 0013950
2023-08-23 10:20	(ALTech) Sangmin Choi	Note Added: 0013951
2023-08-23 10:20	(ALTech) Sangmin Choi	File Added: apple_tv_bt_connect_fail.log
2023-08-24 13:56	(ALTech) Sangmin Choi	Issue Monitored: (ALTech) Sangmin Choi
2023-08-28 09:51	(ALTech) Wooshin Kang	Note Added: 0013999
2023-08-28 09:59	~~(SW) Jason Ling~~	Note Added: 0014000
2023-08-28 10:02	(ALTech) Wooshin Kang	Note Added: 0014002
2023-08-30 11:04	~~(SW) Jason Ling~~	Note Added: 0014020
2023-08-30 11:04	~~(SW) Jason Ling~~	File Added: LMP_Response_Timeout.jpg
2023-08-30 11:04	~~(SW) Jason Ling~~	File Added: bt_stack.jpg
2023-08-30 12:17	(ALTech) Sangmin Choi	Note Added: 0014021
2023-08-30 12:17	(ALTech) Sangmin Choi	File Added: Apple_TV_wireshark.png
2023-08-30 12:17	(ALTech) Sangmin Choi	Note Added: 0014022
2023-08-30 12:17	(ALTech) Sangmin Choi	File Added: Apple_TV_wireshark.jpg
2023-08-30 12:42	(ALTech) Wooshin Kang	Note Added: 0014023
2023-08-30 12:42	(ALTech) Wooshin Kang	File Added: apple_tv.log
2023-08-30 12:45	(ALTech) Wooshin Kang	Note Edited: 0014023
2023-08-30 12:46	(ALTech) Wooshin Kang	Note Added: 0014024
2023-08-30 12:46	(ALTech) Wooshin Kang	File Added: btsnoop_hci.cfa
2023-08-30 12:46	(ALTech) Wooshin Kang	Note Edited: 0014023
2023-08-30 12:48	(ALTech) Wooshin Kang	Note Edited: 0014023
2023-08-30 13:12	(ALTech) Wooshin Kang	Note Edited: 0014023
2023-08-30 13:33	(ALTech) Wooshin Kang	Note Added: 0014025
2023-08-30 13:33	(ALTech) Wooshin Kang	File Added: 스크린샷 2023-08-30 오후 2.32.52.png
2023-08-31 12:46	(ALTech) Wooshin Kang	Note Added: 0014047
2023-08-31 13:35	~~(SW) Jason Ling~~	Note Added: 0014050
2023-09-04 14:48	(ALTech) Wooshin Kang	Note Added: 0014070
2023-09-04 15:00	~~(SW) Jason Ling~~	Note Added: 0014071
2023-09-12 09:27	(ALTech) Wooshin Kang	Note Added: 0014132
2023-09-12 09:47	~~(SW) Jason Ling~~	Note Added: 0014133
2023-09-12 13:58	(ALTech) SY Yoon	Issue Monitored: (ALTech) SY Yoon
2023-09-12 20:10	~~(SW) Jason Ling~~	Note Added: 0014135
2023-09-13 09:40	(ALTech) Wooshin Kang	Note Added: 0014136
2023-09-13 09:40	(ALTech) Wooshin Kang	File Added: btsnoop_hci-2.cfa
2023-09-13 09:40	(ALTech) Wooshin Kang	File Added: apple_tv_0913.log
2023-09-13 11:29	~~(SW) Jason Ling~~	Note Added: 0014137
2023-09-13 11:37	~~(SW) Jason Ling~~	Note Edited: 0014137
2023-09-13 11:38	~~(SW) Jason Ling~~	Note Edited: 0014137
2023-09-13 12:05	(ALTech) Wooshin Kang	Note Added: 0014138
2023-09-13 12:05	(ALTech) Wooshin Kang	Note Edited: 0014138
2023-09-15 16:14	(SW) Sam Yang	Note Added: 0014148
2023-09-15 16:15	(SW) Sam Yang	Status	assigned => feedback
2023-09-15 16:15	(SW) Sam Yang	Issue Monitored: (SW) Sam Yang
2023-09-15 16:39	(ALTech) Wooshin Kang	Note Added: 0014149
2023-09-15 16:39	(ALTech) Wooshin Kang	File Added: IMG_0804.JPG
2023-09-15 16:58	~~(SW) Jason Ling~~	Note Added: 0014150
2023-09-15 17:30	(ALTech) Wooshin Kang	Note Added: 0014151
2023-09-15 17:47	~~(SW) Jason Ling~~	Note Added: 0014152
2023-09-15 18:36	(ALTech) Wooshin Kang	Note Added: 0014153
2023-09-15 18:37	(ALTech) Wooshin Kang	Note Added: 0014154
2023-09-15 18:37	(ALTech) Wooshin Kang	File Added: apple_tv.zip
2023-09-18 09:40	(ALTech) Wooshin Kang	Note Added: 0014157
2023-09-18 09:40	(ALTech) Wooshin Kang	File Added: appletv_0918.log
2023-09-18 09:40	(ALTech) Wooshin Kang	File Added: btsnoop_hci-3.cfa
2023-09-20 10:44	~~(SW) Jason Ling~~	Note Added: 0014180
2023-09-20 10:44	~~(SW) Jason Ling~~	Note Edited: 0014180
2023-09-21 10:40	(ALTech) Wooshin Kang	Note Added: 0014184
2023-09-21 10:40	(ALTech) Wooshin Kang	File Added: AppleTV_0921.zip
2023-09-21 10:41	(ALTech) Wooshin Kang	Note Edited: 0014184
2023-09-25 16:46	(ALTech) Wooshin Kang	Note Added: 0014217
2023-09-26 15:38	~~(SW) Jason Ling~~	Note Added: 0014223
2023-09-26 15:38	~~(SW) Jason Ling~~	Issue Monitored: (SW) Jacky Chiang
2023-10-04 08:32	(ALTech) Wooshin Kang	Note Added: 0014237
2023-10-04 08:50	~~(SW) Jason Ling~~	Note Added: 0014238
2023-10-04 12:05	(ALTech) Wooshin Kang	Note Added: 0014242
2023-10-04 13:36	~~(SW) Jason Ling~~	Note Added: 0014244
2023-10-06 07:34	(ALTech) Wooshin Kang	Note Added: 0014248
2023-10-06 18:25	~~(SW) Jason Ling~~	Note Added: 0014253
2023-10-13 16:49	~~(SW) Jason Ling~~	Note Added: 0014304
2023-10-16 09:16	(ALTech) Wooshin Kang	Note Added: 0014308
2023-10-23 08:16	(ALTech) Wooshin Kang	Note Added: 0014370
2023-10-31 12:00	~~(SW) Jason Ling~~	Note Added: 0014436
2023-10-31 12:00	~~(SW) Jason Ling~~	Status	feedback => resolved
2023-10-31 12:00	~~(SW) Jason Ling~~	Resolution	open => fixed
2025-07-17 17:10	(PM) Sheila Tu	Status	resolved => closed

View Issue Details

Users monitoring this issue

Activities

Issue History